builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-054 starttime: 1487268285.14 results: success (0) revision: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.141347) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.141823) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.142097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.158739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.159112) ========= 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=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 10:04:45-- 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% 111M=0s 2017-02-16 10:04:45 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133603 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.306584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.306918) ========= 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=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018562 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.356437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:45.356774) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 --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 bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 --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=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 10:04:45,428 truncating revision to first 12 chars 2017-02-16 10:04:45,429 Setting DEBUG logging. 2017-02-16 10:04:45,429 attempt 1/10 2017-02-16 10:04:45,429 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bf6b9caab2c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 10:04:45,738 unpacking tar archive at: mozilla-central-bf6b9caab2c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642452 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:46.012976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:46.013404) ========= script_repo_revision: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:46.013845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:46.014156) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:04:46.023909) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-16 10:04:46.024316) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:04:46 INFO - MultiFileLogger online at 20170216 10:04:46 in /builds/slave/test 10:04:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 10:04:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:04:46 INFO - {'append_to_log': False, 10:04:46 INFO - 'base_work_dir': '/builds/slave/test', 10:04:46 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 10:04:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:04:46 INFO - 'branch': 'Firefox-Non-PGO', 10:04:46 INFO - 'buildbot_json_path': 'buildprops.json', 10:04:46 INFO - 'config_files': ('talos/linux_config.py',), 10:04:46 INFO - 'default_actions': ('clobber', 10:04:46 INFO - 'read-buildbot-config', 10:04:46 INFO - 'download-and-extract', 10:04:46 INFO - 'populate-webroot', 10:04:46 INFO - 'create-virtualenv', 10:04:46 INFO - 'install', 10:04:46 INFO - 'run-tests'), 10:04:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:04:46 INFO - 'download_minidump_stackwalk': True, 10:04:46 INFO - 'download_symbols': 'ondemand', 10:04:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:04:46 INFO - 'tooltool.py': '/tools/tooltool.py', 10:04:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:04:46 INFO - '/tools/misc-python/virtualenv.py')}, 10:04:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:04:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:04:46 INFO - 'installer_path': 'installer.exe', 10:04:46 INFO - 'log_level': 'info', 10:04:46 INFO - 'log_name': 'talos', 10:04:46 INFO - 'log_to_console': True, 10:04:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:04:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:04:46 INFO - 'opt_config_files': (), 10:04:46 INFO - 'pip_index': False, 10:04:46 INFO - 'sps_profile': False, 10:04:46 INFO - 'sps_profile_interval': 0, 10:04:46 INFO - 'suite': 'dromaeojs', 10:04:46 INFO - 'system_bits': '32', 10:04:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:04:46 INFO - 'title': 'talos-linux64-ix-054', 10:04:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:04:46 INFO - 'use_talos_json': True, 10:04:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:04:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:04:46 INFO - 'work_dir': 'build'} 10:04:46 INFO - [mozharness: 2017-02-16 18:04:46.200205Z] Running clobber step. 10:04:46 INFO - Running pre-action listener: _resource_record_pre_action 10:04:46 INFO - Running main action method: clobber 10:04:46 INFO - rmtree: /builds/slave/test/build 10:04:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:04:47 INFO - Running post-action listener: _resource_record_post_action 10:04:47 INFO - [mozharness: 2017-02-16 18:04:47.104280Z] Finished clobber step (success) 10:04:47 INFO - [mozharness: 2017-02-16 18:04:47.104380Z] Running read-buildbot-config step. 10:04:47 INFO - Running pre-action listener: _resource_record_pre_action 10:04:47 INFO - Running main action method: read_buildbot_config 10:04:47 INFO - Using buildbot properties: 10:04:47 INFO - { 10:04:47 INFO - "project": "", 10:04:47 INFO - "product": "firefox", 10:04:47 INFO - "who": "kwierso@gmail.com", 10:04:47 INFO - "installer_path": "public/build/target.tar.bz2", 10:04:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:04:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:04:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 10:04:47 INFO - "stage_platform": "linux64", 10:04:47 INFO - "basedir": "/builds/slave/test", 10:04:47 INFO - "buildnumber": 452, 10:04:47 INFO - "platform": "ubuntu64_hw", 10:04:47 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:04:47 INFO - "slavebuilddir": "test", 10:04:47 INFO - "taskId": "Y4Bb61T7TU-60Vsn2PncPQ", 10:04:47 INFO - "branch": "mozilla-central", 10:04:47 INFO - "script_repo_revision": "production", 10:04:47 INFO - "revision": "bf6b9caab2c7eb3ebc642afd82bc19598829c6f3", 10:04:47 INFO - "slavename": "talos-linux64-ix-054", 10:04:47 INFO - "repo_path": "mozilla-central" 10:04:47 INFO - } 10:04:47 INFO - Finding installer, test and symbols from parent task. 10:04:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4Bb61T7TU-60Vsn2PncPQ'}, attempt #1 10:04:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4Bb61T7TU-60Vsn2PncPQ'}, attempt #1 10:04:48 INFO - Task dependencies: LoLaW2TSS7qBYg6WVM6diw 10:04:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw'}, attempt #1 10:04:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 10:04:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/test_packages.json 10:04:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.crashreporter-symbols.zip 10:04:48 INFO - Running post-action listener: _resource_record_post_action 10:04:48 INFO - [mozharness: 2017-02-16 18:04:48.483618Z] Finished read-buildbot-config step (success) 10:04:48 INFO - [mozharness: 2017-02-16 18:04:48.483837Z] Running download-and-extract step. 10:04:48 INFO - Running pre-action listener: _resource_record_pre_action 10:04:48 INFO - Running main action method: download_and_extract 10:04:48 INFO - mkdir: /builds/slave/test/build/tests 10:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:48 INFO - trying https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.test_packages.json 10:04:48 INFO - Downloading https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:04:49 INFO - Downloaded 896 bytes. 10:04:49 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:04:49 INFO - Using the following test package requirements: 10:04:49 INFO - {u'common': [u'target.common.tests.zip'], 10:04:49 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:04:49 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:04:49 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:04:49 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:04:49 INFO - u'mozbase': [u'target.common.tests.zip'], 10:04:49 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:04:49 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:04:49 INFO - u'web-platform': [u'target.common.tests.zip', 10:04:49 INFO - u'target.web-platform.tests.zip'], 10:04:49 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:04:49 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:04:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip 10:04:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:04:49 INFO - Fetch https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip into memory 10:04:50 INFO - Expected file size: 57026229 10:04:50 INFO - Obtained file size: 57026229 10:04:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip 10:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.common.tests.zip into memory 10:05:00 INFO - Expected file size: 57026229 10:05:00 INFO - Obtained file size: 57026229 10:05:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.talos.tests.zip 10:05:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:05:11 INFO - Fetch https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.talos.tests.zip into memory 10:05:11 INFO - Expected file size: 14403647 10:05:11 INFO - Obtained file size: 14403647 10:05: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')]} 10:05:12 INFO - trying https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 10:05:12 INFO - Downloading https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:05:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:05:13 INFO - Downloaded 56544850 bytes. 10:05:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 10:05:13 INFO - mkdir: /builds/slave/test/properties 10:05:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:13 INFO - Contents: 10:05:13 INFO - build_url:https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 10:05:13 INFO - Running post-action listener: _resource_record_post_action 10:05:13 INFO - Running post-action listener: set_extra_try_arguments 10:05:13 INFO - [mozharness: 2017-02-16 18:05:13.369632Z] Finished download-and-extract step (success) 10:05:13 INFO - [mozharness: 2017-02-16 18:05:13.369728Z] Running populate-webroot step. 10:05:13 INFO - Running pre-action listener: _resource_record_pre_action 10:05:13 INFO - Running main action method: populate_webroot 10:05:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:13 INFO - '--tppagecycles', 10:05:13 INFO - '7'], 10:05:13 INFO - 'tests': ['tsvgm']}}, 10:05:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['damp', 'tps']}, 10:05:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'tests': ['damp', 'tps']}, 10:05:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['dromaeo_dom']}, 10:05:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['basic_compositor_video']}, 10:05:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:05:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tsvgx', 10:05:13 INFO - 'tsvgr_opacity', 10:05:13 INFO - 'tart', 10:05:13 INFO - 'tscrollx', 10:05:13 INFO - 'cart', 10:05:13 INFO - 'tsvg_static']}, 10:05:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:13 INFO - 'tsvgr_opacity', 10:05:13 INFO - 'tart', 10:05:13 INFO - 'tscrollx', 10:05:13 INFO - 'cart', 10:05:13 INFO - 'tsvg_static']}, 10:05:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tp5o']}, 10:05:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:05:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s', 10:05:13 INFO - '--xperf_path', 10:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:13 INFO - 'tests': ['tp5n']}, 10:05:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--xperf_path', 10:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:13 INFO - 'tests': ['tp5n']}}, 10:05:13 INFO - 'talos.zip': {'path': '', 10:05:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:13 INFO - Running post-action listener: _resource_record_post_action 10:05:13 INFO - [mozharness: 2017-02-16 18:05:13.382786Z] Finished populate-webroot step (success) 10:05:13 INFO - [mozharness: 2017-02-16 18:05:13.382879Z] Running create-virtualenv step. 10:05:13 INFO - Running pre-action listener: _resource_record_pre_action 10:05:13 INFO - Running main action method: create_virtualenv 10:05:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:13 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 10:05:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:13 INFO - Using real prefix '/usr' 10:05:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:13 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:15 INFO - Installing pip.................done. 10:05:15 INFO - Return code: 0 10:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:05:16 INFO - Reading from file tmpfile_stdout 10:05:16 INFO - Output received: 10:05:16 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) 10:05:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:05:16 INFO - Using env: {'DISPLAY': ':0', 10:05:16 INFO - 'HOME': '/home/cltbld', 10:05:16 INFO - 'LANG': 'en_US.UTF-8', 10:05:16 INFO - 'LANGUAGE': 'en_US:en', 10:05:16 INFO - 'LOGNAME': 'cltbld', 10:05:16 INFO - 'MAIL': '/var/mail/cltbld', 10:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:16 INFO - 'MOZ_NO_REMOTE': '1', 10:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:16 INFO - 'NO_EM_RESTART': '1', 10:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:16 INFO - 'PWD': '/builds/slave/test', 10:05:16 INFO - 'SHELL': '/bin/bash', 10:05:16 INFO - 'SHLVL': '1', 10:05:16 INFO - 'TERM': 'linux', 10:05:16 INFO - 'TMOUT': '86400', 10:05:16 INFO - 'USER': 'cltbld', 10:05:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:16 INFO - '_': '/tools/buildbot/bin/python'} 10:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:16 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 10:05:16 INFO - Cleaning up... 10:05:16 INFO - Return code: 0 10:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:05:16 INFO - Using env: {'DISPLAY': ':0', 10:05:16 INFO - 'HOME': '/home/cltbld', 10:05:16 INFO - 'LANG': 'en_US.UTF-8', 10:05:16 INFO - 'LANGUAGE': 'en_US:en', 10:05:16 INFO - 'LOGNAME': 'cltbld', 10:05:16 INFO - 'MAIL': '/var/mail/cltbld', 10:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:16 INFO - 'MOZ_NO_REMOTE': '1', 10:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:16 INFO - 'NO_EM_RESTART': '1', 10:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:16 INFO - 'PWD': '/builds/slave/test', 10:05:16 INFO - 'SHELL': '/bin/bash', 10:05:16 INFO - 'SHLVL': '1', 10:05:16 INFO - 'TERM': 'linux', 10:05:16 INFO - 'TMOUT': '86400', 10:05:16 INFO - 'USER': 'cltbld', 10:05:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:16 INFO - '_': '/tools/buildbot/bin/python'} 10:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:16 INFO - Downloading/unpacking psutil>=3.1.1 10:05:16 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 10:05:16 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 10:05:16 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 10:05:16 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 10:05:16 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 10:05:16 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 10:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:18 INFO - Installing collected packages: psutil 10:05:18 INFO - Running setup.py install for psutil 10:05:18 INFO - building 'psutil._psutil_linux' extension 10:05:18 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 10:05:18 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 10:05:18 INFO - building 'psutil._psutil_posix' extension 10:05:18 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 10:05:19 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 10:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:19 INFO - Successfully installed psutil 10:05:19 INFO - Cleaning up... 10:05:19 INFO - Return code: 0 10:05:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:05:19 INFO - Using env: {'DISPLAY': ':0', 10:05:19 INFO - 'HOME': '/home/cltbld', 10:05:19 INFO - 'LANG': 'en_US.UTF-8', 10:05:19 INFO - 'LANGUAGE': 'en_US:en', 10:05:19 INFO - 'LOGNAME': 'cltbld', 10:05:19 INFO - 'MAIL': '/var/mail/cltbld', 10:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:19 INFO - 'MOZ_NO_REMOTE': '1', 10:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:19 INFO - 'NO_EM_RESTART': '1', 10:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:19 INFO - 'PWD': '/builds/slave/test', 10:05:19 INFO - 'SHELL': '/bin/bash', 10:05:19 INFO - 'SHLVL': '1', 10:05:19 INFO - 'TERM': 'linux', 10:05:19 INFO - 'TMOUT': '86400', 10:05:19 INFO - 'USER': 'cltbld', 10:05:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:19 INFO - '_': '/tools/buildbot/bin/python'} 10:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:21 INFO - Installing collected packages: mozsystemmonitor 10:05:21 INFO - Running setup.py install for mozsystemmonitor 10:05:21 INFO - Successfully installed mozsystemmonitor 10:05:21 INFO - Cleaning up... 10:05:21 INFO - Return code: 0 10:05:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05:21 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')]} 10:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:21 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')]} 10:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:21 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:21 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 10:05:21 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 10:05:21 INFO - Using env: {'DISPLAY': ':0', 10:05:21 INFO - 'HOME': '/home/cltbld', 10:05:21 INFO - 'LANG': 'en_US.UTF-8', 10:05:21 INFO - 'LANGUAGE': 'en_US:en', 10:05:21 INFO - 'LOGNAME': 'cltbld', 10:05:21 INFO - 'MAIL': '/var/mail/cltbld', 10:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:21 INFO - 'MOZ_NO_REMOTE': '1', 10:05:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:21 INFO - 'NO_EM_RESTART': '1', 10:05:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:21 INFO - 'PWD': '/builds/slave/test', 10:05:21 INFO - 'SHELL': '/bin/bash', 10:05:21 INFO - 'SHLVL': '1', 10:05:21 INFO - 'TERM': 'linux', 10:05:21 INFO - 'TMOUT': '86400', 10:05:21 INFO - 'USER': 'cltbld', 10:05:21 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:21 INFO - '_': '/tools/buildbot/bin/python'} 10:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:21 INFO - Downloading/unpacking jsonschema==2.5.1 10:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:05:23 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 10:05:23 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 10:05:23 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 10:05:23 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 10:05:23 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 10:05:23 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 10:05:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:24 INFO - warning: no files found matching '*.txt' 10:05:24 INFO - no previously-included directories found matching 'build' 10:05:24 INFO - no previously-included directories found matching 'dist' 10:05:24 INFO - no previously-included directories found matching '.git*' 10:05:24 INFO - Installing collected packages: jsonschema, functools32 10:05:24 INFO - Running setup.py install for functools32 10:05:24 INFO - warning: no files found matching '*.txt' 10:05:24 INFO - no previously-included directories found matching 'build' 10:05:24 INFO - no previously-included directories found matching 'dist' 10:05:24 INFO - no previously-included directories found matching '.git*' 10:05:24 INFO - Successfully installed jsonschema functools32 10:05:24 INFO - Cleaning up... 10:05:24 INFO - Return code: 0 10:05:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:05:24 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')]} 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:24 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')]} 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:24 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:24 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 10:05:24 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 10:05:24 INFO - Using env: {'DISPLAY': ':0', 10:05:24 INFO - 'HOME': '/home/cltbld', 10:05:24 INFO - 'LANG': 'en_US.UTF-8', 10:05:24 INFO - 'LANGUAGE': 'en_US:en', 10:05:24 INFO - 'LOGNAME': 'cltbld', 10:05:24 INFO - 'MAIL': '/var/mail/cltbld', 10:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:24 INFO - 'MOZ_NO_REMOTE': '1', 10:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:24 INFO - 'NO_EM_RESTART': '1', 10:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:24 INFO - 'PWD': '/builds/slave/test', 10:05:24 INFO - 'SHELL': '/bin/bash', 10:05:24 INFO - 'SHLVL': '1', 10:05:24 INFO - 'TERM': 'linux', 10:05:24 INFO - 'TMOUT': '86400', 10:05:24 INFO - 'USER': 'cltbld', 10:05:24 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:24 INFO - '_': '/tools/buildbot/bin/python'} 10:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:05:24 INFO - Cleaning up... 10:05:24 INFO - Return code: 0 10:05:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05:24 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')]} 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:24 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')]} 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:24 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:24 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 10:05:24 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 10:05:24 INFO - Using env: {'DISPLAY': ':0', 10:05:24 INFO - 'HOME': '/home/cltbld', 10:05:24 INFO - 'LANG': 'en_US.UTF-8', 10:05:24 INFO - 'LANGUAGE': 'en_US:en', 10:05:24 INFO - 'LOGNAME': 'cltbld', 10:05:24 INFO - 'MAIL': '/var/mail/cltbld', 10:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:24 INFO - 'MOZ_NO_REMOTE': '1', 10:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:24 INFO - 'NO_EM_RESTART': '1', 10:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:24 INFO - 'PWD': '/builds/slave/test', 10:05:24 INFO - 'SHELL': '/bin/bash', 10:05:24 INFO - 'SHLVL': '1', 10:05:24 INFO - 'TERM': 'linux', 10:05:24 INFO - 'TMOUT': '86400', 10:05:24 INFO - 'USER': 'cltbld', 10:05:24 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:24 INFO - '_': '/tools/buildbot/bin/python'} 10:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:24 INFO - Downloading/unpacking blobuploader==1.2.4 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:26 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 INFO - Downloading docopt-0.6.1.tar.gz 10:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:27 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:27 INFO - Running setup.py install for blobuploader 10:05:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:27 INFO - Running setup.py install for requests 10:05:28 INFO - Running setup.py install for docopt 10:05:28 INFO - Successfully installed blobuploader requests docopt 10:05:28 INFO - Cleaning up... 10:05:28 INFO - Return code: 0 10:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:28 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:28 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 10:05:28 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 10:05:28 INFO - Using env: {'DISPLAY': ':0', 10:05:28 INFO - 'HOME': '/home/cltbld', 10:05:28 INFO - 'LANG': 'en_US.UTF-8', 10:05:28 INFO - 'LANGUAGE': 'en_US:en', 10:05:28 INFO - 'LOGNAME': 'cltbld', 10:05:28 INFO - 'MAIL': '/var/mail/cltbld', 10:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:28 INFO - 'MOZ_NO_REMOTE': '1', 10:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:28 INFO - 'NO_EM_RESTART': '1', 10:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:28 INFO - 'PWD': '/builds/slave/test', 10:05:28 INFO - 'SHELL': '/bin/bash', 10:05:28 INFO - 'SHLVL': '1', 10:05:28 INFO - 'TERM': 'linux', 10:05:28 INFO - 'TMOUT': '86400', 10:05:28 INFO - 'USER': 'cltbld', 10:05:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:28 INFO - '_': '/tools/buildbot/bin/python'} 10:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:28 INFO - Running setup.py (path:/tmp/pip-GYttdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:28 INFO - Running setup.py (path:/tmp/pip-oGquAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:28 INFO - Running setup.py (path:/tmp/pip-ZEP12z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:28 INFO - Running setup.py (path:/tmp/pip-HQClTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:28 INFO - Running setup.py (path:/tmp/pip-4pNuFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:28 INFO - Running setup.py (path:/tmp/pip-z1QkbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:28 INFO - Running setup.py (path:/tmp/pip-eHpo0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:28 INFO - Running setup.py (path:/tmp/pip-YIUcE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:29 INFO - Running setup.py (path:/tmp/pip-gCr8a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:29 INFO - Running setup.py (path:/tmp/pip-EmxgHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:29 INFO - Running setup.py (path:/tmp/pip-JJyoQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:29 INFO - Running setup.py (path:/tmp/pip-AW7Gw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:29 INFO - Running setup.py (path:/tmp/pip-uf0Zv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:29 INFO - Running setup.py (path:/tmp/pip-ooJM9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:29 INFO - Running setup.py (path:/tmp/pip-a7lXQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:29 INFO - Running setup.py (path:/tmp/pip-nTBN20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:29 INFO - Running setup.py (path:/tmp/pip-vGnoRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:29 INFO - Running setup.py install for manifestparser 10:05:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:29 INFO - Running setup.py install for mozcrash 10:05:29 INFO - Running setup.py install for mozdebug 10:05:29 INFO - Running setup.py install for mozdevice 10:05:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozfile 10:05:30 INFO - Running setup.py install for mozhttpd 10:05:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozinfo 10:05:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozInstall 10:05:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozleak 10:05:30 INFO - Running setup.py install for mozlog 10:05:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for moznetwork 10:05:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozprocess 10:05:30 INFO - Running setup.py install for mozprofile 10:05:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozrunner 10:05:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozscreenshot 10:05:31 INFO - Running setup.py install for moztest 10:05:31 INFO - Running setup.py install for mozversion 10:05:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:31 INFO - Cleaning up... 10:05:31 INFO - Return code: 0 10:05:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:31 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')]} 10:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:31 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')]} 10:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:31 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:31 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 10:05:31 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 10:05:31 INFO - Using env: {'DISPLAY': ':0', 10:05:31 INFO - 'HOME': '/home/cltbld', 10:05:31 INFO - 'LANG': 'en_US.UTF-8', 10:05:31 INFO - 'LANGUAGE': 'en_US:en', 10:05:31 INFO - 'LOGNAME': 'cltbld', 10:05:31 INFO - 'MAIL': '/var/mail/cltbld', 10:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:31 INFO - 'MOZ_NO_REMOTE': '1', 10:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:31 INFO - 'NO_EM_RESTART': '1', 10:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:31 INFO - 'PWD': '/builds/slave/test', 10:05:31 INFO - 'SHELL': '/bin/bash', 10:05:31 INFO - 'SHLVL': '1', 10:05:31 INFO - 'TERM': 'linux', 10:05:31 INFO - 'TMOUT': '86400', 10:05:31 INFO - 'USER': 'cltbld', 10:05:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:31 INFO - '_': '/tools/buildbot/bin/python'} 10:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:31 INFO - Running setup.py (path:/tmp/pip-dMWgPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:31 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)) 10:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:31 INFO - Running setup.py (path:/tmp/pip-ICKxIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:31 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)) 10:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:31 INFO - Running setup.py (path:/tmp/pip-LtnV7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:31 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)) 10:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:31 INFO - Running setup.py (path:/tmp/pip-vYVIRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:32 INFO - Running setup.py (path:/tmp/pip-ADQYcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:32 INFO - Running setup.py (path:/tmp/pip-NT_YHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:32 INFO - Running setup.py (path:/tmp/pip-mOStIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:32 INFO - Running setup.py (path:/tmp/pip-Hiae4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:32 INFO - Running setup.py (path:/tmp/pip-9AW69o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:32 INFO - Running setup.py (path:/tmp/pip-zgIILg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:32 INFO - Running setup.py (path:/tmp/pip-cGvQGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:32 INFO - Running setup.py (path:/tmp/pip-gNAL5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:32 INFO - Running setup.py (path:/tmp/pip-lnGqXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:32 INFO - Running setup.py (path:/tmp/pip-SEg5xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:32 INFO - Running setup.py (path:/tmp/pip-X3pjqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:32 INFO - Running setup.py (path:/tmp/pip-AXgVuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:32 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)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:32 INFO - Running setup.py (path:/tmp/pip-tkAuR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:32 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)) 10:05:32 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)) 10:05:32 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)) 10:05:32 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)) 10:05:32 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)) 10:05:32 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)) 10:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:32 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 10:05:32 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 10:05:32 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 10:05:32 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 10:05:32 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 10:05:32 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 10:05:34 INFO - Downloading blessings-1.6.tar.gz 10:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:35 INFO - Installing collected packages: blessings 10:05:35 INFO - Running setup.py install for blessings 10:05:35 INFO - Successfully installed blessings 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:35 INFO - Reading from file tmpfile_stdout 10:05:35 INFO - Current package versions: 10:05:35 INFO - argparse == 1.2.1 10:05:35 INFO - blessings == 1.6 10:05:35 INFO - blobuploader == 1.2.4 10:05:35 INFO - docopt == 0.6.1 10:05:35 INFO - functools32 == 3.2.3-2 10:05:35 INFO - jsonschema == 2.5.1 10:05:35 INFO - manifestparser == 1.1 10:05:35 INFO - mozInstall == 1.12 10:05:35 INFO - mozcrash == 1.0 10:05:35 INFO - mozdebug == 0.1 10:05:35 INFO - mozdevice == 0.49 10:05:35 INFO - mozfile == 1.2 10:05:35 INFO - mozhttpd == 0.7 10:05:35 INFO - mozinfo == 0.9 10:05:35 INFO - mozleak == 0.1 10:05:35 INFO - mozlog == 3.4 10:05:35 INFO - moznetwork == 0.27 10:05:35 INFO - mozprocess == 0.24 10:05:35 INFO - mozprofile == 0.28 10:05:35 INFO - mozrunner == 6.13 10:05:35 INFO - mozscreenshot == 0.1 10:05:35 INFO - mozsystemmonitor == 0.3 10:05:35 INFO - moztest == 0.8 10:05:35 INFO - mozversion == 1.4 10:05:35 INFO - psutil == 3.1.1 10:05:35 INFO - requests == 1.2.3 10:05:35 INFO - wsgiref == 0.1.2 10:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:35 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:35 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 10:05:35 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 10:05:35 INFO - Using env: {'DISPLAY': ':0', 10:05:35 INFO - 'HOME': '/home/cltbld', 10:05:35 INFO - 'LANG': 'en_US.UTF-8', 10:05:35 INFO - 'LANGUAGE': 'en_US:en', 10:05:35 INFO - 'LOGNAME': 'cltbld', 10:05:35 INFO - 'MAIL': '/var/mail/cltbld', 10:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:35 INFO - 'MOZ_NO_REMOTE': '1', 10:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:35 INFO - 'NO_EM_RESTART': '1', 10:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:35 INFO - 'PWD': '/builds/slave/test', 10:05:35 INFO - 'SHELL': '/bin/bash', 10:05:35 INFO - 'SHLVL': '1', 10:05:35 INFO - 'TERM': 'linux', 10:05:35 INFO - 'TMOUT': '86400', 10:05:35 INFO - 'USER': 'cltbld', 10:05:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:35 INFO - '_': '/tools/buildbot/bin/python'} 10:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 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)) 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:35 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 0x10ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, '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': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', '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 10:05:35 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 10:05:35 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 10:05:35 INFO - Using env: {'DISPLAY': ':0', 10:05:35 INFO - 'HOME': '/home/cltbld', 10:05:35 INFO - 'LANG': 'en_US.UTF-8', 10:05:35 INFO - 'LANGUAGE': 'en_US:en', 10:05:35 INFO - 'LOGNAME': 'cltbld', 10:05:35 INFO - 'MAIL': '/var/mail/cltbld', 10:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:35 INFO - 'MOZ_NO_REMOTE': '1', 10:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:35 INFO - 'NO_EM_RESTART': '1', 10:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:35 INFO - 'PWD': '/builds/slave/test', 10:05:35 INFO - 'SHELL': '/bin/bash', 10:05:35 INFO - 'SHLVL': '1', 10:05:35 INFO - 'TERM': 'linux', 10:05:35 INFO - 'TMOUT': '86400', 10:05:35 INFO - 'USER': 'cltbld', 10:05:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:35 INFO - '_': '/tools/buildbot/bin/python'} 10:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Running post-action listener: _resource_record_post_action 10:05:35 INFO - Running post-action listener: _start_resource_monitoring 10:05:35 INFO - Starting resource monitoring. 10:05:35 INFO - [mozharness: 2017-02-16 18:05:35.927299Z] Finished create-virtualenv step (success) 10:05:35 INFO - [mozharness: 2017-02-16 18:05:35.927811Z] Running install step. 10:05:35 INFO - Running pre-action listener: _resource_record_pre_action 10:05:35 INFO - Running main action method: install 10:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:36 INFO - Reading from file tmpfile_stdout 10:05:36 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:36 INFO - Reading from file tmpfile_stdout 10:05:36 INFO - Output received: 10:05:36 INFO - Usage: mozinstall [options] installer 10:05:36 INFO - Options: 10:05:36 INFO - -h, --help show this help message and exit 10:05:36 INFO - -d DEST, --destination=DEST 10:05:36 INFO - Directory to install application into. [default: 10:05:36 INFO - "/builds/slave/test"] 10:05:36 INFO - --app=APP Application being installed. [default: firefox] 10:05:36 INFO - mkdir: /builds/slave/test/build/application 10:05:36 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'] 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:05:52 INFO - Reading from file tmpfile_stdout 10:05:52 INFO - Output received: 10:05:52 INFO - /builds/slave/test/build/application/firefox/firefox 10:05:52 INFO - Running post-action listener: _resource_record_post_action 10:05:52 INFO - [mozharness: 2017-02-16 18:05:52.494278Z] Finished install step (success) 10:05:52 INFO - [mozharness: 2017-02-16 18:05:52.494559Z] Running run-tests step. 10:05:52 INFO - Running pre-action listener: _resource_record_pre_action 10:05:52 INFO - Running main action method: run_tests 10:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:05:52 INFO - Python 2.7.3 10:05:52 INFO - Return code: 0 10:05:52 INFO - grabbing minidump binary from tooltool 10:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:52 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 0x1389400>, '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 0x138a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138a840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:05:52 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 10:05:52 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 10:05:52 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 10:05:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:05:52 INFO - Return code: 0 10:05:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:05:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:05:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:05:52 INFO - ENV: RUST_BACKTRACE is now 1 10:05:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:05:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:05:52 INFO - Using env: {'DISPLAY': ':0', 10:05:52 INFO - 'HOME': '/home/cltbld', 10:05:52 INFO - 'LANG': 'en_US.UTF-8', 10:05:52 INFO - 'LANGUAGE': 'en_US:en', 10:05:52 INFO - 'LOGNAME': 'cltbld', 10:05:52 INFO - 'MAIL': '/var/mail/cltbld', 10:05:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:05:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:52 INFO - 'MOZ_NO_REMOTE': '1', 10:05:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:52 INFO - 'NO_EM_RESTART': '1', 10:05:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:52 INFO - 'PWD': '/builds/slave/test', 10:05:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:05:52 INFO - 'RUST_BACKTRACE': '1', 10:05:52 INFO - 'SHELL': '/bin/bash', 10:05:52 INFO - 'SHLVL': '1', 10:05:52 INFO - 'TERM': 'linux', 10:05:52 INFO - 'TMOUT': '86400', 10:05:52 INFO - 'USER': 'cltbld', 10:05:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676', 10:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:52 INFO - '_': '/tools/buildbot/bin/python'} 10:05:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:05:52 INFO - mozversion application_buildid: 20170216172815 10:05:52 INFO - mozversion application_changeset: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 10:05:52 INFO - mozversion application_display_name: Nightly 10:05:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:05:52 INFO - mozversion application_name: Firefox 10:05:52 INFO - mozversion application_remotingname: firefox 10:05:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:05:52 INFO - mozversion application_vendor: Mozilla 10:05:52 INFO - mozversion application_version: 54.0a1 10:05:52 INFO - mozversion platform_buildid: 20170216172815 10:05:52 INFO - mozversion platform_changeset: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 10:05:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:05:52 INFO - mozversion platform_version: 54.0a1 10:05:52 INFO - using testdate: 1487268352 10:05:52 INFO - actual date: 1487268352 10:05:52 INFO - starting webserver on 'localhost:47509' 10:05:52 INFO - SUITE-START | Running 2 tests 10:05:52 INFO - TEST-START | dromaeo_css 10:05:52 INFO - operating with platform_type : linux_ 10:05:52 INFO - Initialising browser for dromaeo_css test... 10:05:52 INFO - TEST-INFO | started process 9457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpncllcq/profile http://localhost:47509/getInfo.html) 10:05:55 INFO - PROCESS | 9457 | Iterating over profile /tmp/tmpncllcq/profile 10:05:56 INFO - PROCESS | 9457 | __metrics Screen width/height:1600/1200 10:05:56 INFO - PROCESS | 9457 | colorDepth:24 10:05:56 INFO - PROCESS | 9457 | Browser inner width/height: 1024/697 10:05:56 INFO - PROCESS | 9457 | __metrics 10:05:56 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:56 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/places.sqlite-wal = Thu Feb 16 2017 10:05:56 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/content-prefs.sqlite = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/compatibility.ini = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/extensions = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/directoryLinks.json = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/bookmarkbackups = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/cache2 = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/localstore.rdf = Thu Feb 16 2017 10:05:11 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/places.sqlite = Thu Feb 16 2017 10:05:57 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/extensions.json = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/addons.json = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/safebrowsing = Thu Feb 16 2017 10:05:57 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/permissions.sqlite = Thu Feb 16 2017 10:05:54 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/key3.db = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/startupCache = Thu Feb 16 2017 10:05:52 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/pluginreg.dat = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | Stat failure 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/cert8.db = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/places.sqlite-shm = Thu Feb 16 2017 10:05:54 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/prefs.js = Thu Feb 16 2017 10:05:11 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/minidumps = Thu Feb 16 2017 10:05:52 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/.parentlock = Thu Feb 16 2017 10:05:52 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/extensions.ini = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/thumbnails = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/gmp = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/secmod.db = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:05:56 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/frequencyCap.json = Thu Feb 16 2017 10:05:55 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/crashes = Thu Feb 16 2017 10:05:52 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/user.js = Thu Feb 16 2017 10:05:52 GMT-0800 (PST) 10:05:57 INFO - PROCESS | 9457 | No birth date. Using mtime. 10:05:57 INFO - PROCESS | 9457 | Using date: /tmp/tmpncllcq/profile/blocklist.xml = Thu Feb 16 2017 10:05:53 GMT-0800 (PST) 10:05:58 INFO - PROCESS | 9457 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:05:58 INFO - PROCESS | 9457 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:05:59 INFO - TEST-INFO | 9457: exit 0 10:05:59 INFO - Browser initialized. 10:05:59 INFO - Running cycle 1/1 for dromaeo_css test... 10:05:59 INFO - TEST-INFO | started process 9538 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpncllcq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:11 INFO - PROCESS | 9538 | RSS: Main: 154812416 10:06:11 INFO - PROCESS | 9538 | 10:09:17 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47509/tests/dromaeo/cssquery-ext.html) 10:09:18 INFO - PROCESS | 9538 | RSS: Main: 282558464 10:09:18 INFO - PROCESS | 9538 | 10:11:00 INFO - PROCESS | 9538 | ************************* 10:11:00 INFO - PROCESS | 9538 | A coding exception was thrown and uncaught in a Task. 10:11:00 INFO - PROCESS | 9538 | 10:11:00 ERROR - PROCESS | 9538 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:11:00 INFO - PROCESS | 9538 | Full stack: 10:11:00 INFO - PROCESS | 9538 | ************************* 10:12:21 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-ext.html (next: http://localhost:47509/tests/dromaeo/cssquery-jquery.html) 10:12:22 INFO - PROCESS | 9538 | RSS: Main: 247349248 10:12:22 INFO - PROCESS | 9538 | 10:15:24 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47509/tests/dromaeo/cssquery-mootools.html) 10:15:25 INFO - PROCESS | 9538 | RSS: Main: 194396160 10:15:25 INFO - PROCESS | 9538 | 10:18:27 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47509/tests/dromaeo/cssquery-prototype.html) 10:18:28 INFO - PROCESS | 9538 | RSS: Main: 223911936 10:18:28 INFO - PROCESS | 9538 | 10:21:31 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47509/tests/dromaeo/cssquery-yui.html) 10:21:31 INFO - PROCESS | 9538 | RSS: Main: 191840256 10:21:31 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | Cycle 1(1): loaded http://localhost:47509/tests/dromaeo/cssquery-yui.html (next: http://localhost:47509/tests/dromaeo/cssquery-dojo.html) 10:24:34 INFO - PROCESS | 9538 | RSS: Main: 249511936 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | __start_tp_report 10:24:34 INFO - PROCESS | 9538 | _x_x_mozilla_page_load 10:24:34 INFO - PROCESS | 9538 | _x_x_mozilla_page_load_details 10:24:34 INFO - PROCESS | 9538 | |i|pagename|runs| 10:24:34 INFO - PROCESS | 9538 | |0;dojo.html;2557.5012872489865;2570.344562136655;2597.9220623381307;2601.9609705854405;2609.5824668053106;4546.749928753918;4617.07658468306;4740.928886066712;4771.45128310244;4785.401824771903;4644.767761611923;4647;4673.76631168442;4727.17274476966;4734.479207287204;4320.395144679732;4340.500842403125;4346.891327716817;4385.166818304512;4459.754713490756;4370.759608221547;4403.669724770639;4412.051408947075;4446.644268458532;4488.775561221943;4181.707280490367;4202.117555313388;4217.704760666755;4381.846635367779;4466.062126953344;4232.597903199191;4248.065425606362;4256.191323648497;4259.595338442843;4261.169072030955;4242.639375653073;4243.872683819491;4259.10558782656;4310.1379724055005;4318.244307246251;4709;4710.811567537295;4756.9286460703115;4757;4774.80900763969;5041.067402530543;5045.873853153664;5048.217526283432;5076.23856421536;5094.108531007096;4951.207806750938;5019.498050194951;5087.949120508784;5090.33825602673;5132.024915266087;4662.976685116552;4714.481407045222;4721.858344249678;4726.432828060655;4727.432708075053;5169.302144210484;5171.120909445403;5172.844814655567;5176.611754118456;5179.559737422362;4947.084789313988;4992.500749925051;5010.64925455222;5062.9746851266245;5138.254953031789;5170.146925757208;5172.19830926207;5173.1722924331925;5173.32746742921;5173.456787037382;5139.460356662571;5152.407473140562;5158.226266060121;5158.484151584811;5199.948000519947;4892.51074892515;4945.3571035765835;4949.7772600233075;5001.874953126201;5007.799688012512;4186.665066794649;4197.0766431385655;4218.303979843292;4220.0293932395725;4220.050488640031;3728.5339332583426;3757.3236817373136;3763.0216143802263;3765;3768.1521657627363;4422.579854913778;4435.6229720473475;4439.511653718088;4472.910541789146;4475.4181956345465;4900.754962251945;5116.56509196715;5126.410462796826;5127.102757017507;5131.87170320745;4350.847720329836;4376.759278239728;4414.713043652152;4434.955650443454;4478.910421791546;4607.95392046082;4616;4618.67669263155;4642.326862604903;4656.604188644003;4812.25410061441;4815.253635686474;4817.590504807131;4858.076965376637;4862.854114376574;4872.220444728827;4957.380327459067;4961.05739909416;5129.974350128375;5139.203423469368;4688.671792974459;4719.2213284808995;4735.83424580138;4755.025219829858;4805.8077676892535;4972.950270497248;4988.401391833003;4997.725125118153;5009.9248511271635;5032.043911656773;5053.7220452875445;5054.924176137288;5056.115179843439;5060.595152387727;5119.0529751996;4768.713877167381;4769.2130798417875;4924.655274130777;4930.704157750403;4982.8754281143265;4850.248211527219;4894.535019173173;4912.238603016538;4974.228994505857;4978.975105124451;4832.95167048339;4837.032593481388;4846.5153484652665;4885.853424397274;4895.094407534618;4903.117438861039;4960.181570040902;4964.379452568429;4964.975175124101;5000.549950504326;4752.500987396274;4776.5701086902345;4789.928151077806;4808.735519546459;4838.274258861199 10:24:34 INFO - PROCESS | 9538 | |1;ext.html;526.899889021086;541.2395584204195;541.9376771671258;544.9073657478228;553.382977979553;26366.472670546576;35789.64210357899;37533.249335013315;37626.247475050484;41741.16517669645;38908.416373754415;39331;39378;39408.60591394085;39422.21155576887;6892.758753443631;7600.163981961966;7811.140774514799;7816.570088645122;7823.374130069581;6851.554648947803;9386.43681379116;9591.712248632552;9614.51927403627;9910.405375677447;9769.609215631366;9819.21446284299;9837.901620983806;9840.803183936318;9841.557129929135;9764.267679924034;9779.755506112404;9839.311248212627;9847.113759761656;9847.261455390839;9564.378315409476;9588.232941364675;9632;9636.132748052709;9641;36075.098122546886;36240.81879590585;36309;36368.63631363679;36456.63543364559;3443.810590417526;3445.242046749711;3461.532693086426;3472.826358682056;3549.574051113883;27171.45657086867;27180.320491987663;27182.417670321287;27247;27283.45433091327;3302.4550949093373;3376.5104059911423;3392.5928888533285;3427.091820667525;3512.912177195565;213386;214221;215028;215635.99999999843;216350;42367.36448953268;43536;50757;51075.84938108668;51188;56318.436815632136;57582.424175757704;57690.42309576851;63735;63963.76579747218;12825.871741282654;13367.598972030855;13697.315134243248;13718.451261949609;13727.725445491234;10197.898021019842;10211.897881021094;10217.23370747182;10358.430286334173;10364.170866330676;15218.847811521742;15570.532884013497;17922.641547168983;17961.640767184585;17981.280748770165;6889.862202755917;7020.508564400545;7036.331548502884;7119.857602847914;7122.964385178145;703.3669697272496;731.7219456606456;731.9048523691886;732.4726197138052;742.9962850185715;26101.608475872876;26601.334966625604;26920.730792692215;26939.191824245303;27086.72913270842;812.3744716568209;910.9863352049724;914.8444764390069;917.9219766319804;922.4096578189964;13957.511487098105;14154;14329.55783707054;14570.635734106732;14609.415623375158;322809;325698;326807;327482;327807;47137.76431117891;47436.288455673195;47440.28839567409;47556;47603;1139.418896362861;1186.371223251678;1186.7092562322287;1195.043964828134;1201.1351826684772;25324.746752532978;26031.971367730985;26620.467590648466;26678.333041674112;26941.595876062267;41298;41924.3711344324;41928.161436771705;41964.58035419607;41977;280056;281321;281524;281793;282068;35392.46911296384;35810;35849;36002.27995440024;36408.81795591005;34739.30521389507;36924.076898077765;37120.81439592893;37512.624873750916;39643.405348920365;34293.65706342905;35279.29441411209;35427.468587971714;36020.64833369138;36417.27165456729;35756.28487430185;35881;35895.28209435744;36148.45777313395;36382.81808591046;35315.293694126485;35347;35391.82304088463;37710.81144594369;39147.80426097851;30721.846390767903;32520.349593007533;32591.34817303593;32969.835150824096;33180.50229246612;24874.875625621757;24975.375615609755;25118;25122.499739362516;25527.872360638077 10:24:34 INFO - PROCESS | 9538 | |2;jquery.html;386.09846009567656;387.2004311097583;390.61719514875415;401.5121627222924;421.5636815895547;17157.656846863072;17339.306427742875;17882.28470861164;17894.910525447354;17965.28138874445;11792.174547781658;11852.703682407922;11978.460969256383;18025.188866501005;18275.95983108765;4781.11549363368;4975.4527002029745;4977.203647416414;4978.800847966077;4979.950200497985;5174.586033117342;5383.327084114486;5431.891362172754;5507.284053073095;5532.723363831812;5316.9734151329185;5525.861853453675;5528.861778455551;5533.170024496338;5533.916991245134;5504.256925315091;5551.084071128259;5584.1902924076185;5589.608727389084;5590.91613625796;5273.34083239595;5457.481539253765;5473.343198816127;5473.452654734535;5487.5609951203805;13199.736005279841;13215.933920330337;13374.531891383755;13376.598702038955;13386.330683465787;2350.21267875262;2395.4131237846664;2417.492326611396;2425.320910145162;2470.8641024743633;9483.668071617529;9993.500324983721;10021.298509104365;12353.93823030888;12446.190997585129;1663.6423169018599;1727.1105380728934;1751.4658029301058;1801.009884078088;1841.3739328628196;75592;76169;76803.99999999945;77781.22218777797;79992.60003699944;10787.244892857581;10852.18608604342;10864.94567527173;11030.834537481944;11106.722331941766;15417.614559636098;15420.768688469681;15460.690786184214;15464.226788660748;15468.381264749283;6906.827329316808;7031.542949708252;7140.964295178491;7194.856102877913;7196.712131514785;6221.2845522764555;6289.7484100636375;6476.481881449473;6490.350964903566;6501.284858665543;4592.448906131285;4737.455192652889;4745.762711864393;4760.476347601761;4835.2505361669;1921.3275353626118;1954.3257576136496;1959.7844237133904;1973.0233534400563;1973.2896157383618;792.7978365516872;810.8337790752881;820.7086484298108;822.6914906909909;837.9078301386843;16916.830831691525;17096.48710538686;17968.730469042974;17998.280068797365;18344.908275458536;821.2403526737744;826.2274773087182;828.3787159630277;828.8010877389383;832.1221111727065;8929.821403571892;9544.284178686627;9848.50757462124;9905.554250058765;10087.848682269772;444203.4637229079;448882;449078;455842;458018;80744;83961;84535.577322113;84889;85593.57203213945;927.6521304510809;939.5396255834592;943.9622415103319;950.3632566180676;955.9866541466068;14873.438288979502;15271.160086195367;15282;15325.310361034004;15747.606309841887;38359.42460863145;39753.4530076035;39950.200995979336;40224.798876005436;40532.1893562133;53249.467505326;54189.45810541844;54296.5977144804;55438;55714.442855572546;7707.653155608124;7885.250901164381;7972.162922893012;8001.559914204542;8061.637226324947;8658.809488288725;11957.940210298893;12270.693232669255;12471.127021108436;12549.309787961749;8671.653133874826;9222.308326875645;9293;10443.425611591436;10797.352158870493;10390.272680912263;10719.517621707198;10817.242793004414;10904;11030.338179709242;9255.814883702424;9456.952715236379;9765.560549775135;9888.291890541048;10182.541785619813;6836.863262734817;6857.314268573103;7074.504784665024;7297.124345078624;7301.707931682673;8741.213290803858;9012.729618111467;9067.501287429255;9302.255819534286;10192.388456692623 10:24:34 INFO - PROCESS | 9538 | |3;mootools.html;1221.7922953097973;1315.1122991980417;1319.0239222975;1322.6693326668333;1335.3790487423353;6265.467435268002;7046.365827075562;7241.493095483318;7251.5286506377115;7454.068241469817;7312.305330993547;7330.413566914647;7359.558426494414;7363.77908662741;7371.1154661440705;2065.2048961149953;2107.947301317464;2108.314797690749;2108.4939614492487;2108.936731898038;1767.1252729898654;1818.3363072478583;2089;2132.744070711509;2135.85049046566;1932.4492519631908;1935.1872213670222;1939.2824654877645;1939.9030048497589;1947.6981067934494;2053.5276886316083;2068.7414073240843;2073.1085633177727;2078.2414418737203;2088.697138914864;2055.969160462594;2063.607914496256;2136.572685462914;2145.3456695707805;2149.8280137588954;7235.167955685059;7244.094488188976;7269.16404613471;7276.454265930023;7278.308560686726;4485.529019452975;4646.4191976003;4653.88365290867;4654.348391225231;4660.580547750718;2920.2553348895894;2922.605448264478;2936.6476022877177;2975.925601859971;2979.7616190704694;1158.6002829023973;1176.9646910592696;1639.6638689068832;1677.5806048487877;1685.4269548353495;9844.212463003014;10001.849972250422;10013.148882345009;10015;10039.096481316716;4018.4173294872667;4030.4760381150263;4060.5127384714024;4224.894377640584;4244.257254980366;7988.440809143421;7991.2807847294025;7995.160508146677;8004.959975200087;8012.759617211493;4615.0077733286835;4615.561521655437;4620.768961551909;4625.606823420047;4632.536746325341;4155.646770024521;4236.703430759817;4242.278812601865;4310.43964284641;4314.80583373749;2897.9275518112217;2955.9704402955695;3284.359549887749;3380.560527131457;3390.660933906639;1040.5889673578895;1070.218740319571;1080.2762149359949;1092.1208427216009;1094.5512339940744;783.8589053970226;803.2048272210469;804.2882049386335;806.8789681547697;812.3460614205621;4647.790849411785;4882.780274887638;4914;4991.126552853236;5142.742862856842;853.472329293106;861.6639510590875;885.8582626779685;911.0434044253508;912.2565109435789;3152.574402455685;3334.3664703706218;3335.166208447888;3346.9330613387597;3432.6910578047596;8117.918820811835;8301.418900676894;8482.10937851529;8604.48373097616;8785.428947118417;8741.956290218635;8742.562871856382;8744.8688269676;8749.125087491328;8755.124487551195;6714.865702686016;6762.323767623297;6888.345607167311;6926.8614627708175;6937.375636192767;4778.665493415567;4936.111499929903;5041;5068.2397640352765;5150.227465879998;7944.165862584346;8015.719449819228;8036.598170091355;8047.465048509701;8150.103488616366;8105.918940810517;8178.5092894426525;8203.0566484855;8226.835463290581;8260.132686068117;6201.1628430163155;6396.104545363746;6397.232332120176;6397.936020639734;6412.166418365583;6081.422264884829;6302.180716506825;6757.391834734897;7025.964870175617;7028.297170282931;6326.114343991937;6359.268684101387;6428.132202152837;6443.484521238383;6464.41820236181;6388.36116388376;6404.039394090923;6425.06836508713;6431.903521447276;6451.1936007999;5444.210589464585;5517.337919449692;5525.364583072998;5526.447355264441;5544.584156188383;4236.745795252172;4242.363645453207;4263.531011588785;4302.591253830881;4387.978060109679;4244.448221731155;4267.445232119929;4283.057727299989;4379.496357918752;4475.5300693426725 10:24:34 INFO - PROCESS | 9538 | |4;prototype.html;345.18536254439516;363.8937629605998;369.50117341588856;369.80769999600227;371.87170426202965;4829.299751536029;4851.563359297663;4884.78018489168;4897.265410188474;4898.142825005623;4623.953760462403;4755.358026666399;4779.259214821708;4802.351682522858;4836.516348365161;3800.619938006191;3803.7337386382965;3806.3909774436097;3811.4092315690973;3815.790131542764;3802.714796390268;3813.523309586302;3819.9045023874346;3822.445745366934;3827.8660246891363;3666.5966743658178;3705.6294370562996;3771.490848735427;3786.1603797152134;3794.6964242860645;3738.7101449999705;3745.5318085239346;3748.9625103748886;3760.2855457462992;3762.0970966967866;3278.918027049319;3279.770416070876;3297.1921879139522;3300.05296874845;3308.3714094322004;4690.202665546831;4690.601298889598;4720.740359280238;4723.409573803275;4724.244120940667;4081.8774237396387;4279.700420970534;4285.742855428684;4298.183345164409;4306.612404883575;5053.67151135175;5054.19132938728;5057.898842023175;5060.873478163038;5098.311727916721;3627.191448978588;3631.5823680276844;3634;3653.94519082214;3657.396529572644;4627.3521706961055;5044.621653376012;5134.717590532557;5329.22726204702;5368.731563421852;3558.2705545363137;3657.012606596204;3657.707383409374;3658.634136586373;3660.926781464356;3728.683061939711;3734.925301494009;3742.868999585056;3746.812659367021;3753.9624603753614;4233.661307095425;4272.658187345005;4290;4293;4294.463192100988;4941.45643979162;5016.740765135234;5054.342935418445;5073.188289873677;5079.949200508022;1252.6492582077033;1257.4658916122316;1262.8484581850237;1265.1649911058657;1267.6070418170398;834.649447232164;871.5075982070236;876.8469462656714;877.1798368525322;878.5826732302105;4379.824807007684;4393.121375724868;4398.428204333416;4408.206522825858;4412.161689279027;5096.464871188546;5110.437851836295;5116.079105760999;5123.897522049539;5131.79472821083;4377.299632058855;4394.494633117232;4399.20814253431;4424.867253982321;4428.889277768018;2506.9498610028063;2681.1286331942197;2685.44948285601;2688.0994866719866;2688.8521131337966;4731.787069581877;4737.715737055788;4757.833475828329;4778.546038126373;4783.569478746929;5020.397552293675;5158.613104017214;5194.519561924909;5208.687478751287;5217.556507696812;4476.776161191928;4844.249141383091;4910.386201724785;4934.309196712391;4936.285847016091;5101.438841727481;5149.588032957279;5219.625070592795;5228.738563071907;5269.499397557226;4900.583450406755;5090;5122.718250496258;5139.717315547531;5174.922376164285;4893.804247830047;4898;4901.534354236342;4902.1911384621135;4916.041371932439;3686.5207523021836;3704.1480459495006;3718.9628103719697;3745.6628903398823;3747.4566187902055;4950.2079667253065;5239.083160446983;5244.659097158673;5246.711430871339;5261.73691315425;5082.043413285837;5098.745062746773;5110.540051395392;5111.79552817898;5112.36095488064;5110.48895110486;5120.948790512047;5121.3342265506735;5130.307408499804;5140.794368225379;4805.615550756001;4978.32792572998;5013.197888337849;5035.672681275852;5037.345145131109;5099.954509325506;5106.540411362995;5119.769610367617;5125.92311115341;5138.640295179451;4956;4983.358819825498;4992.425871024879;4993.151164302005;5010.574101201439 10:24:34 INFO - PROCESS | 9538 | |5;yui.html;638.7221558622002;654.2672207128014;706.9399101076408;717.8241330873935;736.9115706115265;12726.36368181592;12741.299228542426;12991.870081299185;13154.868451315484;13490.527831525898;12903.612891613244;13159.473621055145;13332.533361332355;13339.599812005656;13377.13048651837;804.3565147881701;820.274057459147;820.5322965909434;822.7326119011316;822.9547374894379;733.6698485681438;754.9131849837285;762.839803641236;763.7174245529162;766.4328396986245;880.4453194487496;881.1761003461752;881.3742243007472;882.0385779500343;882.805782727802;873.9344549158839;874.0778478704964;875.7197696737047;877.3595275448959;878.2929741558075;644.7066584703996;646.0632083478974;646.2761706888315;646.4085361893862;646.615263917968;13509.932450337685;13703.794443083361;13741.862581374158;13825.79261311081;13916.58250252494;11634;11701.239419437708;11948.46231919557;11988.160828741904;12069.818952715716;10483.790324193473;10584.100351470135;10597.417142057184;10649;10659.680209593724;3203.4554125798672;3225.5322978168033;3264.281857991238;3274.983625081883;3286.063471910494;59086.704566476896;59157.40842591562;59287.40712592862;60104.69947650234;60303.396966030654;9411.482368469662;9416.90583094174;9459.195968342769;9555.23558115349;9620.470874101851;18839.529011774815;18846.81153188478;18900.81099189018;18906.905465472584;18912.71630925537;6074.42292982166;6222.191115155001;6306.684665766693;6413.230412350455;6530.542861999615;3497.6852083312624;3538.779710463466;3540.6813386794797;3554.3424466473784;3602.2615363850346;2829.405824776779;2840.4177143685497;2865.9426811463654;2880.884764609434;2899.7390234878962;499.1016170892379;501.4208589079619;514.385309555078;514.5035041185274;521.8382301486552;527.9868003299948;539.1023945131312;551.1980068999596;553.2337712268456;555.3585608622049;6594.505412094112;7475.906746110989;7943.9602801986775;8047.959760201162;8064.395170362246;527.6939375162397;548.9615726899159;549.3517649174013;568.7412227436506;577.6158854361886;3575.785452872784;3720.8325625346924;3722.236941427001;3779.7921114338974;3809.12390150267;61686;62705.372946270865;63611.681941589995;63649.363506364345;64543.677281613294;54463;54583;55661.721691391285;56449;58414.7079264601;49465.50534494609;49501.50498495041;50158.247626285636;51672.966540668975;51837;6949.3745562899585;7103.2186459488385;7114.964425177841;7276.818079548054;7282.198958114497;34844.65155348414;35402.64597353993;35607;35763.82118089393;36374.452598532516;42579.7871010643;42779.358309626004;43089.138217236105;43120.56879431166;43340.78329608332;9125.22435592982;9203.493807840365;9279.118483744034;9314.48770317639;9690.37012594179;10468.63359782404;10498.527566259383;10701.946490267499;10739.731506712395;11032.44837758125;9354.766130846783;9431.151196392357;9541.47521886303;9868.210543156674;9981.950090249502;9310;9406.24750019983;9639.132478077006;9660.275479338938;9930.900690993287;8999;9065.50139742294;9115.36192466521;9392.107749763762;9393.342466027312;7322.853542929005;7346.853062938604;7428.814279643052;7549.260346170137;7572.4699271050495;7127.75052873147;7200.603966781878;7229;7319.268073192638;7484.8668129584985 10:24:34 INFO - PROCESS | 9538 | __end_tp_report 10:24:34 INFO - PROCESS | 9538 | __start_cc_report 10:24:34 INFO - PROCESS | 9538 | _x_x_mozilla_cycle_collect,1459 10:24:34 INFO - PROCESS | 9538 | __end_cc_report 10:24:34 INFO - PROCESS | 9538 | __startTimestamp1487269474901__endTimestamp 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | ------- Summary: start ------- 10:24:34 INFO - PROCESS | 9538 | Number of tests: 6 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#0] dojo.html Cycles:165 Average:4673.99 Median:4785.40 stddev:503.14 (10.5%) stddev-sans-first:476.50 10:24:34 INFO - PROCESS | 9538 | Values: 2557.5 2570.3 2597.9 2602.0 2609.6 4546.7 4617.1 4740.9 4771.5 4785.4 4644.8 4647.0 4673.8 4727.2 4734.5 4320.4 4340.5 4346.9 4385.2 4459.8 4370.8 4403.7 4412.1 4446.6 4488.8 4181.7 4202.1 4217.7 4381.8 4466.1 4232.6 4248.1 4256.2 4259.6 4261.2 4242.6 4243.9 4259.1 4310.1 4318.2 4709.0 4710.8 4756.9 4757.0 4774.8 5041.1 5045.9 5048.2 5076.2 5094.1 4951.2 5019.5 5087.9 5090.3 5132.0 4663.0 4714.5 4721.9 4726.4 4727.4 5169.3 5171.1 5172.8 5176.6 5179.6 4947.1 4992.5 5010.6 5063.0 5138.3 5170.1 5172.2 5173.2 5173.3 5173.5 5139.5 5152.4 5158.2 5158.5 5199.9 4892.5 4945.4 4949.8 5001.9 5007.8 4186.7 4197.1 4218.3 4220.0 4220.1 3728.5 3757.3 3763.0 3765.0 3768.2 4422.6 4435.6 4439.5 4472.9 4475.4 4900.8 5116.6 5126.4 5127.1 5131.9 4350.8 4376.8 4414.7 4435.0 4478.9 4608.0 4616.0 4618.7 4642.3 4656.6 4812.3 4815.3 4817.6 4858.1 4862.9 4872.2 4957.4 4961.1 5130.0 5139.2 4688.7 4719.2 4735.8 4755.0 4805.8 4973.0 4988.4 4997.7 5009.9 5032.0 5053.7 5054.9 5056.1 5060.6 5119.1 4768.7 4769.2 4924.7 4930.7 4982.9 4850.2 4894.5 4912.2 4974.2 4979.0 4833.0 4837.0 4846.5 4885.9 4895.1 4903.1 4960.2 4964.4 4965.0 5000.5 4752.5 4776.6 4789.9 4808.7 4838.3 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#1] ext.html Cycles:180 Average:43549.86 Median:36011.46 stddev:72693.45 (201.9%) stddev-sans-first:72825.60 10:24:34 INFO - PROCESS | 9538 | Values: 526.9 541.2 541.9 544.9 553.4 26366.5 35789.6 37533.2 37626.2 41741.2 38908.4 39331.0 39378.0 39408.6 39422.2 6892.8 7600.2 7811.1 7816.6 7823.4 6851.6 9386.4 9591.7 9614.5 9910.4 9769.6 9819.2 9837.9 9840.8 9841.6 9764.3 9779.8 9839.3 9847.1 9847.3 9564.4 9588.2 9632.0 9636.1 9641.0 36075.1 36240.8 36309.0 36368.6 36456.6 3443.8 3445.2 3461.5 3472.8 3549.6 27171.5 27180.3 27182.4 27247.0 27283.5 3302.5 3376.5 3392.6 3427.1 3512.9 213386.0 214221.0 215028.0 215636.0 216350.0 42367.4 43536.0 50757.0 51075.8 51188.0 56318.4 57582.4 57690.4 63735.0 63963.8 12825.9 13367.6 13697.3 13718.5 13727.7 10197.9 10211.9 10217.2 10358.4 10364.2 15218.8 15570.5 17922.6 17961.6 17981.3 6889.9 7020.5 7036.3 7119.9 7123.0 703.4 731.7 731.9 732.5 743.0 26101.6 26601.3 26920.7 26939.2 27086.7 812.4 911.0 914.8 917.9 922.4 13957.5 14154.0 14329.6 14570.6 14609.4 322809.0 325698.0 326807.0 327482.0 327807.0 47137.8 47436.3 47440.3 47556.0 47603.0 1139.4 1186.4 1186.7 1195.0 1201.1 25324.7 26032.0 26620.5 26678.3 26941.6 41298.0 41924.4 41928.2 41964.6 41977.0 280056.0 281321.0 281524.0 281793.0 282068.0 35392.5 35810.0 35849.0 36002.3 36408.8 34739.3 36924.1 37120.8 37512.6 39643.4 34293.7 35279.3 35427.5 36020.6 36417.3 35756.3 35881.0 35895.3 36148.5 36382.8 35315.3 35347.0 35391.8 37710.8 39147.8 30721.8 32520.3 32591.3 32969.8 33180.5 24874.9 24975.4 25118.0 25122.5 25527.9 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#2] jquery.html Cycles:180 Average:26448.34 Median:4979.38 stddev:74509.20 (1496.4%) stddev-sans-first:74692.52 10:24:34 INFO - PROCESS | 9538 | Values: 386.1 387.2 390.6 401.5 421.6 17157.7 17339.3 17882.3 17894.9 17965.3 11792.2 11852.7 11978.5 18025.2 18276.0 4781.1 4975.5 4977.2 4978.8 4980.0 5174.6 5383.3 5431.9 5507.3 5532.7 5317.0 5525.9 5528.9 5533.2 5533.9 5504.3 5551.1 5584.2 5589.6 5590.9 5273.3 5457.5 5473.3 5473.5 5487.6 13199.7 13215.9 13374.5 13376.6 13386.3 2350.2 2395.4 2417.5 2425.3 2470.9 9483.7 9993.5 10021.3 12353.9 12446.2 1663.6 1727.1 1751.5 1801.0 1841.4 75592.0 76169.0 76804.0 77781.2 79992.6 10787.2 10852.2 10864.9 11030.8 11106.7 15417.6 15420.8 15460.7 15464.2 15468.4 6906.8 7031.5 7141.0 7194.9 7196.7 6221.3 6289.7 6476.5 6490.4 6501.3 4592.4 4737.5 4745.8 4760.5 4835.3 1921.3 1954.3 1959.8 1973.0 1973.3 792.8 810.8 820.7 822.7 837.9 16916.8 17096.5 17968.7 17998.3 18344.9 821.2 826.2 828.4 828.8 832.1 8929.8 9544.3 9848.5 9905.6 10087.8 444203.5 448882.0 449078.0 455842.0 458018.0 80744.0 83961.0 84535.6 84889.0 85593.6 927.7 939.5 944.0 950.4 956.0 14873.4 15271.2 15282.0 15325.3 15747.6 38359.4 39753.5 39950.2 40224.8 40532.2 53249.5 54189.5 54296.6 55438.0 55714.4 7707.7 7885.3 7972.2 8001.6 8061.6 8658.8 11957.9 12270.7 12471.1 12549.3 8671.7 9222.3 9293.0 10443.4 10797.4 10390.3 10719.5 10817.2 10904.0 11030.3 9255.8 9457.0 9765.6 9888.3 10182.5 6836.9 6857.3 7074.5 7297.1 7301.7 8741.2 9012.7 9067.5 9302.3 10192.4 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#3] mootools.html Cycles:180 Average:4794.19 Median:4830.72 stddev:2579.42 (53.4%) stddev-sans-first:2572.68 10:24:34 INFO - PROCESS | 9538 | Values: 1221.8 1315.1 1319.0 1322.7 1335.4 6265.5 7046.4 7241.5 7251.5 7454.1 7312.3 7330.4 7359.6 7363.8 7371.1 2065.2 2107.9 2108.3 2108.5 2108.9 1767.1 1818.3 2089.0 2132.7 2135.9 1932.4 1935.2 1939.3 1939.9 1947.7 2053.5 2068.7 2073.1 2078.2 2088.7 2056.0 2063.6 2136.6 2145.3 2149.8 7235.2 7244.1 7269.2 7276.5 7278.3 4485.5 4646.4 4653.9 4654.3 4660.6 2920.3 2922.6 2936.6 2975.9 2979.8 1158.6 1177.0 1639.7 1677.6 1685.4 9844.2 10001.8 10013.1 10015.0 10039.1 4018.4 4030.5 4060.5 4224.9 4244.3 7988.4 7991.3 7995.2 8005.0 8012.8 4615.0 4615.6 4620.8 4625.6 4632.5 4155.6 4236.7 4242.3 4310.4 4314.8 2897.9 2956.0 3284.4 3380.6 3390.7 1040.6 1070.2 1080.3 1092.1 1094.6 783.9 803.2 804.3 806.9 812.3 4647.8 4882.8 4914.0 4991.1 5142.7 853.5 861.7 885.9 911.0 912.3 3152.6 3334.4 3335.2 3346.9 3432.7 8117.9 8301.4 8482.1 8604.5 8785.4 8742.0 8742.6 8744.9 8749.1 8755.1 6714.9 6762.3 6888.3 6926.9 6937.4 4778.7 4936.1 5041.0 5068.2 5150.2 7944.2 8015.7 8036.6 8047.5 8150.1 8105.9 8178.5 8203.1 8226.8 8260.1 6201.2 6396.1 6397.2 6397.9 6412.2 6081.4 6302.2 6757.4 7026.0 7028.3 6326.1 6359.3 6428.1 6443.5 6464.4 6388.4 6404.0 6425.1 6431.9 6451.2 5444.2 5517.3 5525.4 5526.4 5544.6 4236.7 4242.4 4263.5 4302.6 4388.0 4244.4 4267.4 4283.1 4379.5 4475.5 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#4] prototype.html Cycles:180 Average:4186.13 Median:4756.60 stddev:1210.07 (25.4%) stddev-sans-first:1178.62 10:24:34 INFO - PROCESS | 9538 | Values: 345.2 363.9 369.5 369.8 371.9 4829.3 4851.6 4884.8 4897.3 4898.1 4624.0 4755.4 4779.3 4802.4 4836.5 3800.6 3803.7 3806.4 3811.4 3815.8 3802.7 3813.5 3819.9 3822.4 3827.9 3666.6 3705.6 3771.5 3786.2 3794.7 3738.7 3745.5 3749.0 3760.3 3762.1 3278.9 3279.8 3297.2 3300.1 3308.4 4690.2 4690.6 4720.7 4723.4 4724.2 4081.9 4279.7 4285.7 4298.2 4306.6 5053.7 5054.2 5057.9 5060.9 5098.3 3627.2 3631.6 3634.0 3653.9 3657.4 4627.4 5044.6 5134.7 5329.2 5368.7 3558.3 3657.0 3657.7 3658.6 3660.9 3728.7 3734.9 3742.9 3746.8 3754.0 4233.7 4272.7 4290.0 4293.0 4294.5 4941.5 5016.7 5054.3 5073.2 5079.9 1252.6 1257.5 1262.8 1265.2 1267.6 834.6 871.5 876.8 877.2 878.6 4379.8 4393.1 4398.4 4408.2 4412.2 5096.5 5110.4 5116.1 5123.9 5131.8 4377.3 4394.5 4399.2 4424.9 4428.9 2506.9 2681.1 2685.4 2688.1 2688.9 4731.8 4737.7 4757.8 4778.5 4783.6 5020.4 5158.6 5194.5 5208.7 5217.6 4476.8 4844.2 4910.4 4934.3 4936.3 5101.4 5149.6 5219.6 5228.7 5269.5 4900.6 5090.0 5122.7 5139.7 5174.9 4893.8 4898.0 4901.5 4902.2 4916.0 3686.5 3704.1 3719.0 3745.7 3747.5 4950.2 5239.1 5244.7 5246.7 5261.7 5082.0 5098.7 5110.5 5111.8 5112.4 5110.5 5120.9 5121.3 5130.3 5140.8 4805.6 4978.3 5013.2 5035.7 5037.3 5100.0 5106.5 5119.8 5125.9 5138.6 4956.0 4983.4 4992.4 4993.2 5010.6 10:24:34 INFO - PROCESS | 9538 | 10:24:34 INFO - PROCESS | 9538 | [#5] yui.html Cycles:180 Average:13959.88 Median:59122.06 stddev:17801.08 (30.1%) stddev-sans-first:17822.91 10:24:34 INFO - PROCESS | 9538 | Values: 638.7 654.3 706.9 717.8 736.9 12726.4 12741.3 12991.9 13154.9 13490.5 12903.6 13159.5 13332.5 13339.6 13377.1 804.4 820.3 820.5 822.7 823.0 733.7 754.9 762.8 763.7 766.4 880.4 881.2 881.4 882.0 882.8 873.9 874.1 875.7 877.4 878.3 644.7 646.1 646.3 646.4 646.6 13509.9 13703.8 13741.9 13825.8 13916.6 11634.0 11701.2 11948.5 11988.2 12069.8 10483.8 10584.1 10597.4 10649.0 10659.7 3203.5 3225.5 3264.3 3275.0 3286.1 59086.7 59157.4 59287.4 60104.7 60303.4 9411.5 9416.9 9459.2 9555.2 9620.5 18839.5 18846.8 18900.8 18906.9 18912.7 6074.4 6222.2 6306.7 6413.2 6530.5 3497.7 3538.8 3540.7 3554.3 3602.3 2829.4 2840.4 2865.9 2880.9 2899.7 499.1 501.4 514.4 514.5 521.8 528.0 539.1 551.2 553.2 555.4 6594.5 7475.9 7944.0 8048.0 8064.4 527.7 549.0 549.4 568.7 577.6 3575.8 3720.8 3722.2 3779.8 3809.1 61686.0 62705.4 63611.7 63649.4 64543.7 54463.0 54583.0 55661.7 56449.0 58414.7 49465.5 49501.5 50158.2 51673.0 51837.0 6949.4 7103.2 7115.0 7276.8 7282.2 34844.7 35402.6 35607.0 35763.8 36374.5 42579.8 42779.4 43089.1 43120.6 43340.8 9125.2 9203.5 9279.1 9314.5 9690.4 10468.6 10498.5 10701.9 10739.7 11032.4 9354.8 9431.2 9541.5 9868.2 9982.0 9310.0 9406.2 9639.1 9660.3 9930.9 8999.0 9065.5 9115.4 9392.1 9393.3 7322.9 7346.9 7428.8 7549.3 7572.5 7127.8 7200.6 7229.0 7319.3 7484.9 10:24:34 INFO - PROCESS | 9538 | -------- Summary: end -------- 10:24:34 INFO - PROCESS | 9538 | 10:24:35 INFO - TEST-INFO | 9538: exit 0 10:24:35 INFO - TEST-OK | dromaeo_css | took 1123050ms 10:24:35 INFO - TEST-START | kraken 10:24:35 INFO - operating with platform_type : linux_ 10:24:35 INFO - Initialising browser for kraken test... 10:24:35 INFO - TEST-INFO | started process 9840 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_vsmn2/profile http://localhost:47509/getInfo.html) 10:24:38 INFO - PROCESS | 9840 | Iterating over profile /tmp/tmp_vsmn2/profile 10:24:39 INFO - PROCESS | 9840 | __metrics Screen width/height:1600/1200 10:24:39 INFO - PROCESS | 9840 | colorDepth:24 10:24:39 INFO - PROCESS | 9840 | Browser inner width/height: 1024/697 10:24:39 INFO - PROCESS | 9840 | __metrics 10:24:39 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:39 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/places.sqlite-wal = Thu Feb 16 2017 10:24:39 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/content-prefs.sqlite = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/compatibility.ini = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/extensions = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/directoryLinks.json = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/bookmarkbackups = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/cache2 = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/localstore.rdf = Thu Feb 16 2017 10:05:11 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/places.sqlite = Thu Feb 16 2017 10:24:39 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/extensions.json = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/addons.json = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/safebrowsing = Thu Feb 16 2017 10:24:39 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/permissions.sqlite = Thu Feb 16 2017 10:24:37 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/key3.db = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/startupCache = Thu Feb 16 2017 10:24:35 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/pluginreg.dat = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | Stat failure 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/cert8.db = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/places.sqlite-shm = Thu Feb 16 2017 10:24:37 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/prefs.js = Thu Feb 16 2017 10:05:11 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/minidumps = Thu Feb 16 2017 10:24:35 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/.parentlock = Thu Feb 16 2017 10:24:35 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/extensions.ini = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/thumbnails = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/gmp = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/secmod.db = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:24:39 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/frequencyCap.json = Thu Feb 16 2017 10:24:38 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/crashes = Thu Feb 16 2017 10:24:35 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/user.js = Thu Feb 16 2017 10:24:35 GMT-0800 (PST) 10:24:40 INFO - PROCESS | 9840 | No birth date. Using mtime. 10:24:40 INFO - PROCESS | 9840 | Using date: /tmp/tmp_vsmn2/profile/blocklist.xml = Thu Feb 16 2017 10:24:36 GMT-0800 (PST) 10:24:41 INFO - PROCESS | 9840 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:24:41 INFO - PROCESS | 9840 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:24:42 INFO - TEST-INFO | 9840: exit 0 10:24:42 INFO - Browser initialized. 10:24:42 INFO - Running cycle 1/1 for kraken test... 10:24:42 INFO - TEST-INFO | started process 9923 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_vsmn2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:54 INFO - PROCESS | 9923 | RSS: Main: 154714112 10:24:54 INFO - PROCESS | 9923 | 10:24:56 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47509/tests/kraken/driver.html?testName=audio-beat-detection) 10:24:57 INFO - PROCESS | 9923 | RSS: Main: 209948672 10:24:57 INFO - PROCESS | 9923 | 10:25:00 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47509/tests/kraken/driver.html?testName=audio-dft) 10:25:01 INFO - PROCESS | 9923 | RSS: Main: 189394944 10:25:01 INFO - PROCESS | 9923 | 10:25:04 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47509/tests/kraken/driver.html?testName=audio-fft) 10:25:04 INFO - PROCESS | 9923 | RSS: Main: 271880192 10:25:04 INFO - PROCESS | 9923 | 10:25:07 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47509/tests/kraken/driver.html?testName=audio-oscillator) 10:25:07 INFO - PROCESS | 9923 | RSS: Main: 183316480 10:25:07 INFO - PROCESS | 9923 | 10:25:10 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47509/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:10 INFO - PROCESS | 9923 | RSS: Main: 185958400 10:25:10 INFO - PROCESS | 9923 | 10:25:13 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47509/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:14 INFO - PROCESS | 9923 | RSS: Main: 224739328 10:25:14 INFO - PROCESS | 9923 | 10:25:17 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47509/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:18 INFO - PROCESS | 9923 | RSS: Main: 219234304 10:25:18 INFO - PROCESS | 9923 | 10:25:21 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47509/tests/kraken/driver.html?testName=json-parse-financial) 10:25:21 INFO - PROCESS | 9923 | RSS: Main: 221224960 10:25:21 INFO - PROCESS | 9923 | 10:25:23 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47509/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:25:24 INFO - PROCESS | 9923 | RSS: Main: 184418304 10:25:24 INFO - PROCESS | 9923 | 10:25:26 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:25:26 INFO - PROCESS | 9923 | RSS: Main: 191950848 10:25:26 INFO - PROCESS | 9923 | 10:25:28 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:25:29 INFO - PROCESS | 9923 | RSS: Main: 200171520 10:25:29 INFO - PROCESS | 9923 | 10:25:32 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:25:33 INFO - PROCESS | 9923 | RSS: Main: 210272256 10:25:33 INFO - PROCESS | 9923 | 10:25:36 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:25:36 INFO - PROCESS | 9923 | RSS: Main: 206454784 10:25:36 INFO - PROCESS | 9923 | 10:25:38 INFO - PROCESS | 9923 | Cycle 1(1): loaded http://localhost:47509/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47509/tests/kraken/driver.html?testName=ai-astar) 10:25:39 INFO - PROCESS | 9923 | RSS: Main: 258531328 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | __start_tp_report 10:25:39 INFO - PROCESS | 9923 | _x_x_mozilla_page_load 10:25:39 INFO - PROCESS | 9923 | _x_x_mozilla_page_load_details 10:25:39 INFO - PROCESS | 9923 | |i|pagename|runs| 10:25:39 INFO - PROCESS | 9923 | |0;ai-astar;108;115;114;115;115;115;115;114;114;115 10:25:39 INFO - PROCESS | 9923 | |1;audio-beat-detection;152;125;124;176;129;127;128;127;147;133 10:25:39 INFO - PROCESS | 9923 | |2;audio-dft;161;162;163;164;163;162;161;161;160;161 10:25:39 INFO - PROCESS | 9923 | |3;audio-fft;77;77;77;124;78;77;78;78;99;80 10:25:39 INFO - PROCESS | 9923 | |4;audio-oscillator;85;84;118;83;84;84;102;84;84;112 10:25:39 INFO - PROCESS | 9923 | |5;imaging-gaussian-blur;113;113;114;114;114;113;114;113;114;114 10:25:39 INFO - PROCESS | 9923 | |6;imaging-darkroom;150;149;151;149;149;150;151;150;150;149 10:25:39 INFO - PROCESS | 9923 | |7;imaging-desaturate;95;95;95;96;95;95;95;97;96;96 10:25:39 INFO - PROCESS | 9923 | |8;json-parse-financial;61;62;62;64;62;62;61;62;62;61 10:25:39 INFO - PROCESS | 9923 | |9;json-stringify-tinderbox;65;69;64;64;64;64;64;64;65;64 10:25:39 INFO - PROCESS | 9923 | |10;stanford-crypto-aes;88;100;88;87;88;87;88;97;88;89 10:25:39 INFO - PROCESS | 9923 | |11;stanford-crypto-ccm;138;139;138;136;140;150;137;146;187;148 10:25:39 INFO - PROCESS | 9923 | |12;stanford-crypto-pbkdf2;187;187;187;183;185;189;185;184;184;185 10:25:39 INFO - PROCESS | 9923 | |13;stanford-crypto-sha256-iterative;80;70;71;71;71;71;70;70;81;71 10:25:39 INFO - PROCESS | 9923 | __end_tp_report 10:25:39 INFO - PROCESS | 9923 | __start_cc_report 10:25:39 INFO - PROCESS | 9923 | _x_x_mozilla_cycle_collect,2066 10:25:39 INFO - PROCESS | 9923 | __end_cc_report 10:25:39 INFO - PROCESS | 9923 | __startTimestamp1487269539033__endTimestamp 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | ------- Summary: start ------- 10:25:39 INFO - PROCESS | 9923 | Number of tests: 14 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:2.16 (1.9%) stddev-sans-first:0.50 10:25:39 INFO - PROCESS | 9923 | Values: 108.0 115.0 114.0 115.0 115.0 115.0 115.0 114.0 114.0 115.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:131.00 stddev:16.73 (12.8%) stddev-sans-first:16.82 10:25:39 INFO - PROCESS | 9923 | Values: 152.0 125.0 124.0 176.0 129.0 127.0 128.0 127.0 147.0 133.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 10:25:39 INFO - PROCESS | 9923 | Values: 161.0 162.0 163.0 164.0 163.0 162.0 161.0 161.0 160.0 161.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#3] audio-fft Cycles:10 Average:84.50 Median:78.00 stddev:15.43 (19.8%) stddev-sans-first:16.12 10:25:39 INFO - PROCESS | 9923 | Values: 77.0 77.0 77.0 124.0 78.0 77.0 78.0 78.0 99.0 80.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.00 stddev:13.44 (16.0%) stddev-sans-first:14.02 10:25:39 INFO - PROCESS | 9923 | Values: 85.0 84.0 118.0 83.0 84.0 84.0 102.0 84.0 84.0 112.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:25:39 INFO - PROCESS | 9923 | Values: 113.0 113.0 114.0 114.0 114.0 113.0 114.0 113.0 114.0 114.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 10:25:39 INFO - PROCESS | 9923 | Values: 150.0 149.0 151.0 149.0 149.0 150.0 151.0 150.0 150.0 149.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 10:25:39 INFO - PROCESS | 9923 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 97.0 96.0 96.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:0.88 (1.4%) stddev-sans-first:0.87 10:25:39 INFO - PROCESS | 9923 | Values: 61.0 62.0 62.0 64.0 62.0 62.0 61.0 62.0 62.0 61.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:64.00 stddev:1.57 (2.4%) stddev-sans-first:1.66 10:25:39 INFO - PROCESS | 9923 | Values: 65.0 69.0 64.0 64.0 64.0 64.0 64.0 64.0 65.0 64.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:88.00 stddev:4.57 (5.2%) stddev-sans-first:4.79 10:25:39 INFO - PROCESS | 9923 | Values: 88.0 100.0 88.0 87.0 88.0 87.0 88.0 97.0 88.0 89.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:143.00 stddev:15.26 (10.7%) stddev-sans-first:15.91 10:25:39 INFO - PROCESS | 9923 | Values: 138.0 139.0 138.0 136.0 140.0 150.0 137.0 146.0 187.0 148.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:186.00 stddev:1.84 (1.0%) stddev-sans-first:1.88 10:25:39 INFO - PROCESS | 9923 | Values: 187.0 187.0 187.0 183.0 185.0 189.0 185.0 184.0 184.0 185.0 10:25:39 INFO - PROCESS | 9923 | 10:25:39 INFO - PROCESS | 9923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:71.00 stddev:4.20 (5.9%) stddev-sans-first:3.49 10:25:39 INFO - PROCESS | 9923 | Values: 80.0 70.0 71.0 71.0 71.0 71.0 70.0 70.0 81.0 71.0 10:25:39 INFO - PROCESS | 9923 | -------- Summary: end -------- 10:25:39 INFO - PROCESS | 9923 | 10:25:40 INFO - TEST-INFO | 9923: exit 0 10:25:40 INFO - TEST-OK | kraken | took 64411ms 10:25:40 INFO - SUITE-END | took 1187s 10:25:40 INFO - Completed test suite (00:19:47) 10:25:40 INFO - javascript benchmark 10:25:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6244.903292834823, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2557.5012872489865, 2570.344562136655, 2597.9220623381307, 2601.9609705854405, 2609.5824668053106, 4546.749928753918, 4617.07658468306, 4740.928886066712, 4771.45128310244, 4785.401824771903, 4644.767761611923, 4647.0, 4673.76631168442, 4727.17274476966, 4734.479207287204, 4320.395144679732, 4340.500842403125, 4346.891327716817, 4385.166818304512, 4459.754713490756, 4370.759608221547, 4403.669724770639, 4412.051408947075, 4446.644268458532, 4488.775561221943, 4181.707280490367, 4202.117555313388, 4217.704760666755, 4381.846635367779, 4466.062126953344, 4232.597903199191, 4248.065425606362, 4256.191323648497, 4259.595338442843, 4261.169072030955, 4242.639375653073, 4243.872683819491, 4259.10558782656, 4310.1379724055005, 4318.244307246251, 4709.0, 4710.811567537295, 4756.9286460703115, 4757.0, 4774.80900763969, 5041.067402530543, 5045.873853153664, 5048.217526283432, 5076.23856421536, 5094.108531007096, 4951.207806750938, 5019.498050194951, 5087.949120508784, 5090.33825602673, 5132.024915266087, 4662.976685116552, 4714.481407045222, 4721.858344249678, 4726.432828060655, 4727.432708075053, 5169.302144210484, 5171.120909445403, 5172.844814655567, 5176.611754118456, 5179.559737422362, 4947.084789313988, 4992.500749925051, 5010.64925455222, 5062.9746851266245, 5138.254953031789, 5170.146925757208, 5172.19830926207, 5173.1722924331925, 5173.32746742921, 5173.456787037382, 5139.460356662571, 5152.407473140562, 5158.226266060121, 5158.484151584811, 5199.948000519947, 4892.51074892515, 4945.3571035765835, 4949.7772600233075, 5001.874953126201, 5007.799688012512, 4186.665066794649, 4197.0766431385655, 4218.303979843292, 4220.0293932395725, 4220.050488640031, 3728.5339332583426, 3757.3236817373136, 3763.0216143802263, 3765.0, 3768.1521657627363, 4422.579854913778, 4435.6229720473475, 4439.511653718088, 4472.910541789146, 4475.4181956345465, 4900.754962251945, 5116.56509196715, 5126.410462796826, 5127.102757017507, 5131.87170320745, 4350.847720329836, 4376.759278239728, 4414.713043652152, 4434.955650443454, 4478.910421791546, 4607.95392046082, 4616.0, 4618.67669263155, 4642.326862604903, 4656.604188644003, 4812.25410061441, 4815.253635686474, 4817.590504807131, 4858.076965376637, 4862.854114376574, 4872.220444728827, 4957.380327459067, 4961.05739909416, 5129.974350128375, 5139.203423469368, 4688.671792974459, 4719.2213284808995, 4735.83424580138, 4755.025219829858, 4805.8077676892535, 4972.950270497248, 4988.401391833003, 4997.725125118153, 5009.9248511271635, 5032.043911656773, 5053.7220452875445, 5054.924176137288, 5056.115179843439, 5060.595152387727, 5119.0529751996, 4768.713877167381, 4769.2130798417875, 4924.655274130777, 4930.704157750403, 4982.8754281143265, 4850.248211527219, 4894.535019173173, 4912.238603016538, 4974.228994505857, 4978.975105124451, 4832.95167048339, 4837.032593481388, 4846.5153484652665, 4885.853424397274, 4895.094407534618, 4903.117438861039, 4960.181570040902, 4964.379452568429, 4964.975175124101, 5000.549950504326, 4752.500987396274, 4776.5701086902345, 4789.928151077806, 4808.735519546459, 4838.274258861199], "value": 4640.794668526195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.899889021086, 541.2395584204195, 541.9376771671258, 544.9073657478228, 553.382977979553, 26366.472670546576, 35789.64210357899, 37533.249335013315, 37626.247475050484, 41741.16517669645, 38908.416373754415, 39331.0, 39378.0, 39408.60591394085, 39422.21155576887, 6892.758753443631, 7600.163981961966, 7811.140774514799, 7816.570088645122, 7823.374130069581, 6851.554648947803, 9386.43681379116, 9591.712248632552, 9614.51927403627, 9910.405375677447, 9769.609215631366, 9819.21446284299, 9837.901620983806, 9840.803183936318, 9841.557129929135, 9764.267679924034, 9779.755506112404, 9839.311248212627, 9847.113759761656, 9847.261455390839, 9564.378315409476, 9588.232941364675, 9632.0, 9636.132748052709, 9641.0, 36075.098122546886, 36240.81879590585, 36309.0, 36368.63631363679, 36456.63543364559, 3443.810590417526, 3445.242046749711, 3461.532693086426, 3472.826358682056, 3549.574051113883, 27171.45657086867, 27180.320491987663, 27182.417670321287, 27247.0, 27283.45433091327, 3302.4550949093373, 3376.5104059911423, 3392.5928888533285, 3427.091820667525, 3512.912177195565, 213386.0, 214221.0, 215028.0, 215635.99999999843, 216350.0, 42367.36448953268, 43536.0, 50757.0, 51075.84938108668, 51188.0, 56318.436815632136, 57582.424175757704, 57690.42309576851, 63735.0, 63963.76579747218, 12825.871741282654, 13367.598972030855, 13697.315134243248, 13718.451261949609, 13727.725445491234, 10197.898021019842, 10211.897881021094, 10217.23370747182, 10358.430286334173, 10364.170866330676, 15218.847811521742, 15570.532884013497, 17922.641547168983, 17961.640767184585, 17981.280748770165, 6889.862202755917, 7020.508564400545, 7036.331548502884, 7119.857602847914, 7122.964385178145, 703.3669697272496, 731.7219456606456, 731.9048523691886, 732.4726197138052, 742.9962850185715, 26101.608475872876, 26601.334966625604, 26920.730792692215, 26939.191824245303, 27086.72913270842, 812.3744716568209, 910.9863352049724, 914.8444764390069, 917.9219766319804, 922.4096578189964, 13957.511487098105, 14154.0, 14329.55783707054, 14570.635734106732, 14609.415623375158, 322809.0, 325698.0, 326807.0, 327482.0, 327807.0, 47137.76431117891, 47436.288455673195, 47440.28839567409, 47556.0, 47603.0, 1139.418896362861, 1186.371223251678, 1186.7092562322287, 1195.043964828134, 1201.1351826684772, 25324.746752532978, 26031.971367730985, 26620.467590648466, 26678.333041674112, 26941.595876062267, 41298.0, 41924.3711344324, 41928.161436771705, 41964.58035419607, 41977.0, 280056.0, 281321.0, 281524.0, 281793.0, 282068.0, 35392.46911296384, 35810.0, 35849.0, 36002.27995440024, 36408.81795591005, 34739.30521389507, 36924.076898077765, 37120.81439592893, 37512.624873750916, 39643.405348920365, 34293.65706342905, 35279.29441411209, 35427.468587971714, 36020.64833369138, 36417.27165456729, 35756.28487430185, 35881.0, 35895.28209435744, 36148.45777313395, 36382.81808591046, 35315.293694126485, 35347.0, 35391.82304088463, 37710.81144594369, 39147.80426097851, 30721.846390767903, 32520.349593007533, 32591.34817303593, 32969.835150824096, 33180.50229246612, 24874.875625621757, 24975.375615609755, 25118.0, 25122.499739362516, 25527.872360638077], "value": 17353.91556499935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.09846009567656, 387.2004311097583, 390.61719514875415, 401.5121627222924, 421.5636815895547, 17157.656846863072, 17339.306427742875, 17882.28470861164, 17894.910525447354, 17965.28138874445, 11792.174547781658, 11852.703682407922, 11978.460969256383, 18025.188866501005, 18275.95983108765, 4781.11549363368, 4975.4527002029745, 4977.203647416414, 4978.800847966077, 4979.950200497985, 5174.586033117342, 5383.327084114486, 5431.891362172754, 5507.284053073095, 5532.723363831812, 5316.9734151329185, 5525.861853453675, 5528.861778455551, 5533.170024496338, 5533.916991245134, 5504.256925315091, 5551.084071128259, 5584.1902924076185, 5589.608727389084, 5590.91613625796, 5273.34083239595, 5457.481539253765, 5473.343198816127, 5473.452654734535, 5487.5609951203805, 13199.736005279841, 13215.933920330337, 13374.531891383755, 13376.598702038955, 13386.330683465787, 2350.21267875262, 2395.4131237846664, 2417.492326611396, 2425.320910145162, 2470.8641024743633, 9483.668071617529, 9993.500324983721, 10021.298509104365, 12353.93823030888, 12446.190997585129, 1663.6423169018599, 1727.1105380728934, 1751.4658029301058, 1801.009884078088, 1841.3739328628196, 75592.0, 76169.0, 76803.99999999945, 77781.22218777797, 79992.60003699944, 10787.244892857581, 10852.18608604342, 10864.94567527173, 11030.834537481944, 11106.722331941766, 15417.614559636098, 15420.768688469681, 15460.690786184214, 15464.226788660748, 15468.381264749283, 6906.827329316808, 7031.542949708252, 7140.964295178491, 7194.856102877913, 7196.712131514785, 6221.2845522764555, 6289.7484100636375, 6476.481881449473, 6490.350964903566, 6501.284858665543, 4592.448906131285, 4737.455192652889, 4745.762711864393, 4760.476347601761, 4835.2505361669, 1921.3275353626118, 1954.3257576136496, 1959.7844237133904, 1973.0233534400563, 1973.2896157383618, 792.7978365516872, 810.8337790752881, 820.7086484298108, 822.6914906909909, 837.9078301386843, 16916.830831691525, 17096.48710538686, 17968.730469042974, 17998.280068797365, 18344.908275458536, 821.2403526737744, 826.2274773087182, 828.3787159630277, 828.8010877389383, 832.1221111727065, 8929.821403571892, 9544.284178686627, 9848.50757462124, 9905.554250058765, 10087.848682269772, 444203.4637229079, 448882.0, 449078.0, 455842.0, 458018.0, 80744.0, 83961.0, 84535.577322113, 84889.0, 85593.57203213945, 927.6521304510809, 939.5396255834592, 943.9622415103319, 950.3632566180676, 955.9866541466068, 14873.438288979502, 15271.160086195367, 15282.0, 15325.310361034004, 15747.606309841887, 38359.42460863145, 39753.4530076035, 39950.200995979336, 40224.798876005436, 40532.1893562133, 53249.467505326, 54189.45810541844, 54296.5977144804, 55438.0, 55714.442855572546, 7707.653155608124, 7885.250901164381, 7972.162922893012, 8001.559914204542, 8061.637226324947, 8658.809488288725, 11957.940210298893, 12270.693232669255, 12471.127021108436, 12549.309787961749, 8671.653133874826, 9222.308326875645, 9293.0, 10443.425611591436, 10797.352158870493, 10390.272680912263, 10719.517621707198, 10817.242793004414, 10904.0, 11030.338179709242, 9255.814883702424, 9456.952715236379, 9765.560549775135, 9888.291890541048, 10182.541785619813, 6836.863262734817, 6857.314268573103, 7074.504784665024, 7297.124345078624, 7301.707931682673, 8741.213290803858, 9012.729618111467, 9067.501287429255, 9302.255819534286, 10192.388456692623], "value": 8307.037800780694, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1221.7922953097973, 1315.1122991980417, 1319.0239222975, 1322.6693326668333, 1335.3790487423353, 6265.467435268002, 7046.365827075562, 7241.493095483318, 7251.5286506377115, 7454.068241469817, 7312.305330993547, 7330.413566914647, 7359.558426494414, 7363.77908662741, 7371.1154661440705, 2065.2048961149953, 2107.947301317464, 2108.314797690749, 2108.4939614492487, 2108.936731898038, 1767.1252729898654, 1818.3363072478583, 2089.0, 2132.744070711509, 2135.85049046566, 1932.4492519631908, 1935.1872213670222, 1939.2824654877645, 1939.9030048497589, 1947.6981067934494, 2053.5276886316083, 2068.7414073240843, 2073.1085633177727, 2078.2414418737203, 2088.697138914864, 2055.969160462594, 2063.607914496256, 2136.572685462914, 2145.3456695707805, 2149.8280137588954, 7235.167955685059, 7244.094488188976, 7269.16404613471, 7276.454265930023, 7278.308560686726, 4485.529019452975, 4646.4191976003, 4653.88365290867, 4654.348391225231, 4660.580547750718, 2920.2553348895894, 2922.605448264478, 2936.6476022877177, 2975.925601859971, 2979.7616190704694, 1158.6002829023973, 1176.9646910592696, 1639.6638689068832, 1677.5806048487877, 1685.4269548353495, 9844.212463003014, 10001.849972250422, 10013.148882345009, 10015.0, 10039.096481316716, 4018.4173294872667, 4030.4760381150263, 4060.5127384714024, 4224.894377640584, 4244.257254980366, 7988.440809143421, 7991.2807847294025, 7995.160508146677, 8004.959975200087, 8012.759617211493, 4615.0077733286835, 4615.561521655437, 4620.768961551909, 4625.606823420047, 4632.536746325341, 4155.646770024521, 4236.703430759817, 4242.278812601865, 4310.43964284641, 4314.80583373749, 2897.9275518112217, 2955.9704402955695, 3284.359549887749, 3380.560527131457, 3390.660933906639, 1040.5889673578895, 1070.218740319571, 1080.2762149359949, 1092.1208427216009, 1094.5512339940744, 783.8589053970226, 803.2048272210469, 804.2882049386335, 806.8789681547697, 812.3460614205621, 4647.790849411785, 4882.780274887638, 4914.0, 4991.126552853236, 5142.742862856842, 853.472329293106, 861.6639510590875, 885.8582626779685, 911.0434044253508, 912.2565109435789, 3152.574402455685, 3334.3664703706218, 3335.166208447888, 3346.9330613387597, 3432.6910578047596, 8117.918820811835, 8301.418900676894, 8482.10937851529, 8604.48373097616, 8785.428947118417, 8741.956290218635, 8742.562871856382, 8744.8688269676, 8749.125087491328, 8755.124487551195, 6714.865702686016, 6762.323767623297, 6888.345607167311, 6926.8614627708175, 6937.375636192767, 4778.665493415567, 4936.111499929903, 5041.0, 5068.2397640352765, 5150.227465879998, 7944.165862584346, 8015.719449819228, 8036.598170091355, 8047.465048509701, 8150.103488616366, 8105.918940810517, 8178.5092894426525, 8203.0566484855, 8226.835463290581, 8260.132686068117, 6201.1628430163155, 6396.104545363746, 6397.232332120176, 6397.936020639734, 6412.166418365583, 6081.422264884829, 6302.180716506825, 6757.391834734897, 7025.964870175617, 7028.297170282931, 6326.114343991937, 6359.268684101387, 6428.132202152837, 6443.484521238383, 6464.41820236181, 6388.36116388376, 6404.039394090923, 6425.06836508713, 6431.903521447276, 6451.1936007999, 5444.210589464585, 5517.337919449692, 5525.364583072998, 5526.447355264441, 5544.584156188383, 4236.745795252172, 4242.363645453207, 4263.531011588785, 4302.591253830881, 4387.978060109679, 4244.448221731155, 4267.445232119929, 4283.057727299989, 4379.496357918752, 4475.5300693426725], "value": 3935.7376927366513, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.18536254439516, 363.8937629605998, 369.50117341588856, 369.80769999600227, 371.87170426202965, 4829.299751536029, 4851.563359297663, 4884.78018489168, 4897.265410188474, 4898.142825005623, 4623.953760462403, 4755.358026666399, 4779.259214821708, 4802.351682522858, 4836.516348365161, 3800.619938006191, 3803.7337386382965, 3806.3909774436097, 3811.4092315690973, 3815.790131542764, 3802.714796390268, 3813.523309586302, 3819.9045023874346, 3822.445745366934, 3827.8660246891363, 3666.5966743658178, 3705.6294370562996, 3771.490848735427, 3786.1603797152134, 3794.6964242860645, 3738.7101449999705, 3745.5318085239346, 3748.9625103748886, 3760.2855457462992, 3762.0970966967866, 3278.918027049319, 3279.770416070876, 3297.1921879139522, 3300.05296874845, 3308.3714094322004, 4690.202665546831, 4690.601298889598, 4720.740359280238, 4723.409573803275, 4724.244120940667, 4081.8774237396387, 4279.700420970534, 4285.742855428684, 4298.183345164409, 4306.612404883575, 5053.67151135175, 5054.19132938728, 5057.898842023175, 5060.873478163038, 5098.311727916721, 3627.191448978588, 3631.5823680276844, 3634.0, 3653.94519082214, 3657.396529572644, 4627.3521706961055, 5044.621653376012, 5134.717590532557, 5329.22726204702, 5368.731563421852, 3558.2705545363137, 3657.012606596204, 3657.707383409374, 3658.634136586373, 3660.926781464356, 3728.683061939711, 3734.925301494009, 3742.868999585056, 3746.812659367021, 3753.9624603753614, 4233.661307095425, 4272.658187345005, 4290.0, 4293.0, 4294.463192100988, 4941.45643979162, 5016.740765135234, 5054.342935418445, 5073.188289873677, 5079.949200508022, 1252.6492582077033, 1257.4658916122316, 1262.8484581850237, 1265.1649911058657, 1267.6070418170398, 834.649447232164, 871.5075982070236, 876.8469462656714, 877.1798368525322, 878.5826732302105, 4379.824807007684, 4393.121375724868, 4398.428204333416, 4408.206522825858, 4412.161689279027, 5096.464871188546, 5110.437851836295, 5116.079105760999, 5123.897522049539, 5131.79472821083, 4377.299632058855, 4394.494633117232, 4399.20814253431, 4424.867253982321, 4428.889277768018, 2506.9498610028063, 2681.1286331942197, 2685.44948285601, 2688.0994866719866, 2688.8521131337966, 4731.787069581877, 4737.715737055788, 4757.833475828329, 4778.546038126373, 4783.569478746929, 5020.397552293675, 5158.613104017214, 5194.519561924909, 5208.687478751287, 5217.556507696812, 4476.776161191928, 4844.249141383091, 4910.386201724785, 4934.309196712391, 4936.285847016091, 5101.438841727481, 5149.588032957279, 5219.625070592795, 5228.738563071907, 5269.499397557226, 4900.583450406755, 5090.0, 5122.718250496258, 5139.717315547531, 5174.922376164285, 4893.804247830047, 4898.0, 4901.534354236342, 4902.1911384621135, 4916.041371932439, 3686.5207523021836, 3704.1480459495006, 3718.9628103719697, 3745.6628903398823, 3747.4566187902055, 4950.2079667253065, 5239.083160446983, 5244.659097158673, 5246.711430871339, 5261.73691315425, 5082.043413285837, 5098.745062746773, 5110.540051395392, 5111.79552817898, 5112.36095488064, 5110.48895110486, 5120.948790512047, 5121.3342265506735, 5130.307408499804, 5140.794368225379, 4805.615550756001, 4978.32792572998, 5013.197888337849, 5035.672681275852, 5037.345145131109, 5099.954509325506, 5106.540411362995, 5119.769610367617, 5125.92311115341, 5138.640295179451, 4956.0, 4983.358819825498, 4992.425871024879, 4993.151164302005, 5010.574101201439], "value": 3818.4148531930623, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.7221558622002, 654.2672207128014, 706.9399101076408, 717.8241330873935, 736.9115706115265, 12726.36368181592, 12741.299228542426, 12991.870081299185, 13154.868451315484, 13490.527831525898, 12903.612891613244, 13159.473621055145, 13332.533361332355, 13339.599812005656, 13377.13048651837, 804.3565147881701, 820.274057459147, 820.5322965909434, 822.7326119011316, 822.9547374894379, 733.6698485681438, 754.9131849837285, 762.839803641236, 763.7174245529162, 766.4328396986245, 880.4453194487496, 881.1761003461752, 881.3742243007472, 882.0385779500343, 882.805782727802, 873.9344549158839, 874.0778478704964, 875.7197696737047, 877.3595275448959, 878.2929741558075, 644.7066584703996, 646.0632083478974, 646.2761706888315, 646.4085361893862, 646.615263917968, 13509.932450337685, 13703.794443083361, 13741.862581374158, 13825.79261311081, 13916.58250252494, 11634.0, 11701.239419437708, 11948.46231919557, 11988.160828741904, 12069.818952715716, 10483.790324193473, 10584.100351470135, 10597.417142057184, 10649.0, 10659.680209593724, 3203.4554125798672, 3225.5322978168033, 3264.281857991238, 3274.983625081883, 3286.063471910494, 59086.704566476896, 59157.40842591562, 59287.40712592862, 60104.69947650234, 60303.396966030654, 9411.482368469662, 9416.90583094174, 9459.195968342769, 9555.23558115349, 9620.470874101851, 18839.529011774815, 18846.81153188478, 18900.81099189018, 18906.905465472584, 18912.71630925537, 6074.42292982166, 6222.191115155001, 6306.684665766693, 6413.230412350455, 6530.542861999615, 3497.6852083312624, 3538.779710463466, 3540.6813386794797, 3554.3424466473784, 3602.2615363850346, 2829.405824776779, 2840.4177143685497, 2865.9426811463654, 2880.884764609434, 2899.7390234878962, 499.1016170892379, 501.4208589079619, 514.385309555078, 514.5035041185274, 521.8382301486552, 527.9868003299948, 539.1023945131312, 551.1980068999596, 553.2337712268456, 555.3585608622049, 6594.505412094112, 7475.906746110989, 7943.9602801986775, 8047.959760201162, 8064.395170362246, 527.6939375162397, 548.9615726899159, 549.3517649174013, 568.7412227436506, 577.6158854361886, 3575.785452872784, 3720.8325625346924, 3722.236941427001, 3779.7921114338974, 3809.12390150267, 61686.0, 62705.372946270865, 63611.681941589995, 63649.363506364345, 64543.677281613294, 54463.0, 54583.0, 55661.721691391285, 56449.0, 58414.7079264601, 49465.50534494609, 49501.50498495041, 50158.247626285636, 51672.966540668975, 51837.0, 6949.3745562899585, 7103.2186459488385, 7114.964425177841, 7276.818079548054, 7282.198958114497, 34844.65155348414, 35402.64597353993, 35607.0, 35763.82118089393, 36374.452598532516, 42579.7871010643, 42779.358309626004, 43089.138217236105, 43120.56879431166, 43340.78329608332, 9125.22435592982, 9203.493807840365, 9279.118483744034, 9314.48770317639, 9690.37012594179, 10468.63359782404, 10498.527566259383, 10701.946490267499, 10739.731506712395, 11032.44837758125, 9354.766130846783, 9431.151196392357, 9541.47521886303, 9868.210543156674, 9981.950090249502, 9310.0, 9406.24750019983, 9639.132478077006, 9660.275479338938, 9930.900690993287, 8999.0, 9065.50139742294, 9115.36192466521, 9392.107749763762, 9393.342466027312, 7322.853542929005, 7346.853062938604, 7428.814279643052, 7549.260346170137, 7572.4699271050495, 7127.75052873147, 7200.603966781878, 7229.0, 7319.268073192638, 7484.8668129584985], "value": 5898.719019277689, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 114.0, 115.0, 115.0, 115.0, 115.0, 114.0, 114.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 124.0, 176.0, 129.0, 127.0, 128.0, 127.0, 147.0, 133.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 164.0, 163.0, 162.0, 161.0, 161.0, 160.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 124.0, 78.0, 77.0, 78.0, 78.0, 99.0, 80.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 83.0, 84.0, 84.0, 102.0, 84.0, 84.0, 112.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 151.0, 149.0, 149.0, 150.0, 151.0, 150.0, 150.0, 149.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 97.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 64.0, 62.0, 62.0, 61.0, 62.0, 62.0, 61.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 88.0, 87.0, 88.0, 87.0, 88.0, 97.0, 88.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 138.0, 136.0, 140.0, 150.0, 137.0, 146.0, 187.0, 148.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 187.0, 183.0, 185.0, 189.0, 185.0, 184.0, 184.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 71.0, 71.0, 71.0, 71.0, 70.0, 70.0, 81.0, 71.0], "value": 72.6, "unit": "score"}]}]} 10:25:40 INFO - Return code: 0 10:25:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:25:40 ERROR - # TBPL SUCCESS # 10:25:40 INFO - Running post-action listener: _resource_record_post_action 10:25:40 INFO - [mozharness: 2017-02-16 18:25:40.832952Z] Finished run-tests step (success) 10:25:40 INFO - Running post-run listener: _resource_record_post_run 10:25:40 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 258048; Write bytes: 402911232; Read time: 96; Write time: 287104 10:25:40 INFO - TinderboxPrint: CPU usage
13.1% 10:25:40 INFO - TinderboxPrint: I/O read bytes / time
258,048 / 96 10:25:40 INFO - TinderboxPrint: I/O write bytes / time
402,911,232 / 287,104 10:25:40 INFO - TinderboxPrint: CPU idle
8,347.8 (86.9%) 10:25:40 INFO - TinderboxPrint: CPU user
1,215.6 (12.6%) 10:25:40 INFO - TinderboxPrint: Swap in / out
0 / 0 10:25:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105406464; Read time: 0; Write time: 107316 10:25:40 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 253952; Write bytes: 296890368; Read time: 80; Write time: 179584 10:25:41 INFO - Running post-run listener: _upload_blobber_files 10:25:41 INFO - Blob upload gear active. 10:25:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:41 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 10:25:41 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'] 10:25:41 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 10:25:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:42 INFO - (blobuploader) - INFO - Done attempting. 10:25:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:25:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:46 INFO - (blobuploader) - INFO - Done attempting. 10:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:46 INFO - (blobuploader) - INFO - Done attempting. 10:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:47 INFO - (blobuploader) - INFO - Done attempting. 10:25:47 INFO - (blobuploader) - INFO - Iteration through files over. 10:25:47 INFO - Return code: 0 10:25:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:25:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:25:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af11c8afe76574076c4ce46784174f6e336a84fbe997841ed11d066d6f67651aff1b6ee918dc4338be99a4ea69c65396ac873865596bedc46cfda8404b43ca30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b67fdeed8a1200fd9906497182b67a425f9e9e18c325890f81e455e748a16a8e5ca7e094c86913702522cfa6647afea49dece4f329cc808394c1670a5503b93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9bf0ef496bcc72ccec06ff6c90865d45c40ec88edbd36b24ce08d0134003a1605e58a0627cfad0cd6dff30f16ed5d652e4b68325dbac2d48e6cb3c60b33a822", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/927e285f82cb17380ffaa80a3ae166ab38bd087427bc9ad7ba55cdc47ce6efdc2aad8c83ac3b2e3356ba08cacd38c68f16e3a4199950dd4ad3dd49ff2f085f68"} 10:25:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:25:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:25:47 INFO - Contents: 10:25:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af11c8afe76574076c4ce46784174f6e336a84fbe997841ed11d066d6f67651aff1b6ee918dc4338be99a4ea69c65396ac873865596bedc46cfda8404b43ca30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b67fdeed8a1200fd9906497182b67a425f9e9e18c325890f81e455e748a16a8e5ca7e094c86913702522cfa6647afea49dece4f329cc808394c1670a5503b93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9bf0ef496bcc72ccec06ff6c90865d45c40ec88edbd36b24ce08d0134003a1605e58a0627cfad0cd6dff30f16ed5d652e4b68325dbac2d48e6cb3c60b33a822", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/927e285f82cb17380ffaa80a3ae166ab38bd087427bc9ad7ba55cdc47ce6efdc2aad8c83ac3b2e3356ba08cacd38c68f16e3a4199950dd4ad3dd49ff2f085f68"} 10:25:47 INFO - Running post-run listener: copy_logs_to_upload_dir 10:25:47 INFO - Copying logs to upload dir... 10:25:47 INFO - mkdir: /builds/slave/test/build/upload/logs 10:25:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.653402 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-16 10:25:47.687352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:25:47.690843) ========= 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=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af11c8afe76574076c4ce46784174f6e336a84fbe997841ed11d066d6f67651aff1b6ee918dc4338be99a4ea69c65396ac873865596bedc46cfda8404b43ca30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b67fdeed8a1200fd9906497182b67a425f9e9e18c325890f81e455e748a16a8e5ca7e094c86913702522cfa6647afea49dece4f329cc808394c1670a5503b93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9bf0ef496bcc72ccec06ff6c90865d45c40ec88edbd36b24ce08d0134003a1605e58a0627cfad0cd6dff30f16ed5d652e4b68325dbac2d48e6cb3c60b33a822", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/927e285f82cb17380ffaa80a3ae166ab38bd087427bc9ad7ba55cdc47ce6efdc2aad8c83ac3b2e3356ba08cacd38c68f16e3a4199950dd4ad3dd49ff2f085f68"} build_url:https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/LoLaW2TSS7qBYg6WVM6diw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af11c8afe76574076c4ce46784174f6e336a84fbe997841ed11d066d6f67651aff1b6ee918dc4338be99a4ea69c65396ac873865596bedc46cfda8404b43ca30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b67fdeed8a1200fd9906497182b67a425f9e9e18c325890f81e455e748a16a8e5ca7e094c86913702522cfa6647afea49dece4f329cc808394c1670a5503b93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9bf0ef496bcc72ccec06ff6c90865d45c40ec88edbd36b24ce08d0134003a1605e58a0627cfad0cd6dff30f16ed5d652e4b68325dbac2d48e6cb3c60b33a822", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/927e285f82cb17380ffaa80a3ae166ab38bd087427bc9ad7ba55cdc47ce6efdc2aad8c83ac3b2e3356ba08cacd38c68f16e3a4199950dd4ad3dd49ff2f085f68"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:25:47.715803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:25:47.716103) ========= 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=cbe409c6de289db6ac707de3000001d4-1487268284.516559-725646676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:25:47.800196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:25:47.800564) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:25:47.800872) ========= ========= Total master_lag: 0.16 =========